2023-11-03 18:58:47,402 INFO subiquity:199 Starting Subiquity server revision 41 of snap /snap/ubuntu-budgie-installer/41 2023-11-03 18:58:47,402 INFO subiquity:200 Arguments passed: ['/snap/ubuntu-budgie-installer/41/bin/subiquity/subiquity/cmd/server.py', '--use-os-prober', '--storage-version=2', '--postinst-hooks-dir=/snap/ubuntu-budgie-installer/41/etc/subiquity/postinst.d'] 2023-11-03 18:58:47,402 DEBUG subiquity:201 Kernel commandline: CommandLineParams(_raw='BOOT_IMAGE=/casper/vmlinuz layerfs-path=minimal.standard.live.squashfs --- quiet splash\n', _tokens={'quiet', 'splash', '---'}, _values={'BOOT_IMAGE': '/casper/vmlinuz', 'layerfs-path': 'minimal.standard.live.squashfs'}) 2023-11-03 18:58:47,403 DEBUG subiquity:202 Environment: environ({'SNAP_REVISION': '41', 'SNAP_REAL_HOME': '/root', 'SNAP_USER_COMMON': '/root/snap/ubuntu-budgie-installer/common', 'PATH_ORIG': '/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', 'SNAP_INSTANCE_KEY': '', 'SNAP_EUID': '0', 'PWD': '/snap/ubuntu-budgie-installer/41/bin/subiquity', 'SYSTEMD_EXEC_PID': '3385', 'SNAP_CONTEXT': '8mWBdJ_tluOQhaHkzl-kWsgzwF7SA_PVpvF0fNCpEl8NBnoKHqMB', 'LANG': 'C.UTF-8', 'SNAP_ARCH': 'amd64', 'PY3OR2_PYTHON': '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', 'SNAP_INSTANCE_NAME': 'ubuntu-budgie-installer', 'SNAP_USER_DATA': '/root/snap/ubuntu-budgie-installer/41', 'INVOCATION_ID': 'd7c4d70032514ce2899219d24688ab76', 'SNAP_REEXEC': '', 'SNAP_UID': '0', 'LD_LIBRARY_PATH_ORIG': '/var/lib/snapd/lib/gl:/var/lib/snapd/lib/gl32:/var/lib/snapd/void:/snap/ubuntu-budgie-installer/41/lib:/snap/ubuntu-budgie-installer/41/usr/lib:/snap/ubuntu-budgie-installer/41/lib/x86_64-linux-gnu:/snap/ubuntu-budgie-installer/41/usr/lib/x86_64-linux-gnu', 'PYTHONPATH_ORIG': '', 'PYTHONPATH': '/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', 'PYTHON': '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', 'PYTHONIOENCODING': 'utf-8', 'SNAP': '/snap/ubuntu-budgie-installer/41', 'SNAP_COMMON': '/var/snap/ubuntu-budgie-installer/common', 'SNAP_VERSION': '0+git.b963da6', 'DISPLAY': ':0', 'SHLVL': '1', 'SNAP_LIBRARY_PATH': '/var/lib/snapd/lib/gl:/var/lib/snapd/lib/gl32:/var/lib/snapd/void', 'SNAP_COOKIE': '8mWBdJ_tluOQhaHkzl-kWsgzwF7SA_PVpvF0fNCpEl8NBnoKHqMB', 'SNAP_DATA': '/var/snap/ubuntu-budgie-installer/41', 'LD_LIBRARY_PATH': '/var/lib/snapd/lib/gl:/var/lib/snapd/lib/gl32:/var/lib/snapd/void:/snap/ubuntu-budgie-installer/41/lib:/snap/ubuntu-budgie-installer/41/usr/lib:/snap/ubuntu-budgie-installer/41/lib/x86_64-linux-gnu:/snap/ubuntu-budgie-installer/41/usr/lib/x86_64-linux-gnu', 'SNAP_NAME': 'ubuntu-budgie-installer', 'JOURNAL_STREAM': '8:29323', 'PATH': '/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', 'SUBIQUITY_ROOT': '/snap/ubuntu-budgie-installer/41/bin/subiquity', 'OLDPWD': '/var/snap/ubuntu-budgie-installer/41', '_': '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', 'APPORT_DATA_DIR': '/snap/ubuntu-budgie-installer/41/bin/subiquity/share/apport'}) 2023-11-03 18:58:47,403 DEBUG asyncio:54 Using selector: EpollSelector 2023-11-03 18:58:47,404 DEBUG subiquitycore.prober:33 Prober() init finished, data:None 2023-11-03 18:58:47,510 DEBUG curtin:95 Running command ['dpkg', '--print-architecture'] with allowed return codes [0] (capture=True) 2023-11-03 18:58:47,542 DEBUG subiquitycore.utils:121 arun_command called: ['cloud-init', 'status', '--wait'] 2023-11-03 18:58:47,902 DEBUG curtin.reporting.start.subiquity/Meta/status_GET:45 start: subiquity/Meta/status_GET: 2023-11-03 18:58:47,903 DEBUG curtin.reporting.finish.subiquity/Meta/status_GET:45 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "CLOUD_INIT_WAIT", "confirming_tty": "", "error": null, "cloud_init... 2023-11-03 18:58:48,906 DEBUG curtin.reporting.start.subiquity/Meta/status_GET:45 start: subiquity/Meta/status_GET: 2023-11-03 18:58:48,907 DEBUG curtin.reporting.finish.subiquity/Meta/status_GET:45 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "CLOUD_INIT_WAIT", "confirming_tty": "", "error": null, "cloud_init... 2023-11-03 18:58:49,856 DEBUG subiquitycore.utils:135 arun_command ['cloud-init', 'status', '--wait'] exited with code 0 2023-11-03 18:58:49,857 DEBUG subiquity.server.server:569 waited 2.3148629665374756s for cloud-init 2023-11-03 18:58:49,857 DEBUG subiquity.server.server:573 cloud-init status: '\nstatus: disabled\n', assumed disabled 2023-11-03 18:58:49,858 DEBUG subiquity.server.server:470 load_autoinstall_config only_early True file None 2023-11-03 18:58:49,858 DEBUG subiquity.server.server:470 load_autoinstall_config only_early False file None 2023-11-03 18:58:49,859 DEBUG subiquitycore.core:118 starting controllers 2023-11-03 18:58:49,863 DEBUG subiquity.server.controllers.kernel:63 Using kernel linux-generic-hwe-22.04 due to /etc/subiquity/kernel-meta-package 2023-11-03 18:58:49,871 DEBUG subiquity.models.source:93 loaded 2 sources from '/cdrom/casper/install-sources.yaml' 2023-11-03 18:58:49,873 DEBUG probert.network:585 event for link_change: NEW {'ifindex': 1, 'flags': 65609, 'arptype': 772, 'family': 0, 'is_vlan': False, 'name': b'lo'} 2023-11-03 18:58:49,873 DEBUG probert.network:585 event for link_change: NEW {'ifindex': 2, 'flags': 69699, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'enp2s0'} 2023-11-03 18:58:49,873 DEBUG probert.network:585 event for link_change: NEW {'ifindex': 3, 'flags': 4099, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'wlp0s12f0'} 2023-11-03 18:58:49,873 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 1, 'flags': 128, 'family': 2, 'scope': 254, 'local': b'127.0.0.1/8'} 2023-11-03 18:58:49,874 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'192.168.1.116/24'} 2023-11-03 18:58:49,874 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 1, 'flags': 128, 'family': 10, 'scope': 254, 'local': b'::1'} 2023-11-03 18:58:49,874 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 2, 'flags': 640, 'family': 10, 'scope': 253, 'local': b'fe80::6662:1ea4:cb32:3ab9/64'} 2023-11-03 18:58:49,874 DEBUG probert.network:672 link_change NEW {'ifindex': 1, 'flags': 65609, 'arptype': 772, 'family': 0, 'is_vlan': False, 'name': b'lo'} 2023-11-03 18:58:50,049 DEBUG subiquitycore.models.network:475 new_link 1 lo lo 2023-11-03 18:58:50,049 DEBUG subiquitycore.models.network:477 ignoring based on type 2023-11-03 18:58:50,049 DEBUG probert.network:672 link_change NEW {'ifindex': 2, 'flags': 69699, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'enp2s0'} 2023-11-03 18:58:50,225 DEBUG subiquitycore.models.network:475 new_link 2 enp2s0 eth 2023-11-03 18:58:50,226 DEBUG subiquitycore.models.network:506 new_link 2 enp2s0 with config {} 2023-11-03 18:58:50,226 DEBUG root:30 start: subiquity/Network/_send_update: NEW enp2s0 2023-11-03 18:58:50,226 DEBUG root:30 finish: subiquity/Network/_send_update: SUCCESS: NEW enp2s0 2023-11-03 18:58:50,226 DEBUG probert.network:672 link_change NEW {'ifindex': 3, 'flags': 4099, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'wlp0s12f0'} 2023-11-03 18:58:50,400 DEBUG subiquitycore.models.network:475 new_link 3 wlp0s12f0 wlan 2023-11-03 18:58:50,401 DEBUG subiquitycore.models.network:506 new_link 3 wlp0s12f0 with config {} 2023-11-03 18:58:50,401 DEBUG subiquity.server.controllers.network:100 maybe_start_install_wpasupplicant 2023-11-03 18:58:50,401 DEBUG root:30 start: subiquity/Network/_send_update: NEW wlp0s12f0 2023-11-03 18:58:50,401 DEBUG root:30 finish: subiquity/Network/_send_update: SUCCESS: NEW wlp0s12f0 2023-11-03 18:58:50,402 DEBUG probert.network:717 addr_change NEW {'ifindex': 1, 'flags': 128, 'family': 2, 'scope': 254, 'local': b'127.0.0.1/8'} 2023-11-03 18:58:50,402 DEBUG probert.network:717 addr_change NEW {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'192.168.1.116/24'} 2023-11-03 18:58:50,402 DEBUG root:30 start: subiquity/Network/_send_update: CHANGE enp2s0 2023-11-03 18:58:50,402 DEBUG root:30 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp2s0 2023-11-03 18:58:50,402 DEBUG probert.network:717 addr_change NEW {'ifindex': 1, 'flags': 128, 'family': 10, 'scope': 254, 'local': b'::1'} 2023-11-03 18:58:50,402 DEBUG probert.network:717 addr_change NEW {'ifindex': 2, 'flags': 640, 'family': 10, 'scope': 253, 'local': b'fe80::6662:1ea4:cb32:3ab9/64'} 2023-11-03 18:58:50,403 DEBUG root:30 start: subiquity/Network/_send_update: CHANGE enp2s0 2023-11-03 18:58:50,403 DEBUG root:30 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp2s0 2023-11-03 18:58:50,403 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 2} 2023-11-03 18:58:50,500 DEBUG pyroute2.ndb.140494004154912.sources.localhost:74 init 2023-11-03 18:58:50,500 DEBUG pyroute2.ndb.140494004154912.sources.localhost:384 starting the source 2023-11-03 18:58:50,501 DEBUG pyroute2.ndb.140494004154912.sources.localhost/nsmanager:74 init 2023-11-03 18:58:50,501 DEBUG pyroute2.ndb.140494004154912.sources.localhost:74 connecting 2023-11-03 18:58:50,501 DEBUG pyroute2.ndb.140494004154912.sources.localhost:74 loading 2023-11-03 18:58:50,502 DEBUG pyroute2.ndb.140494004154912.sources.localhost/nsmanager:384 starting the source 2023-11-03 18:58:50,502 DEBUG pyroute2.ndb.140494004154912.sources.localhost/nsmanager:74 connecting 2023-11-03 18:58:50,505 DEBUG pyroute2.ndb.140494004154912.sources.localhost/nsmanager:74 loading 2023-11-03 18:58:50,531 DEBUG pyroute2.ndb.140494004154912.sources.localhost:74 running 2023-11-03 18:58:50,534 DEBUG pyroute2.ndb.140494004154912.sources.localhost/nsmanager:74 running 2023-11-03 18:58:50,536 DEBUG pyroute2.ndb.140494004154912.sources.localhost:399 source shutdown 2023-11-03 18:58:50,536 DEBUG pyroute2.ndb.140494004154912.sources.localhost:374 sync 2023-11-03 18:58:50,536 DEBUG pyroute2.ndb.140494004154912.sources.localhost:369 shutdown handled by the main thread 2023-11-03 18:58:50,536 DEBUG pyroute2.ndb.140494004154912.sources.localhost:74 stopped 2023-11-03 18:58:50,537 DEBUG pyroute2.ndb.140494004154912.main:851 flush DB for the target localhost 2023-11-03 18:58:50,538 DEBUG pyroute2.ndb.140494004154912.sources.localhost/nsmanager:399 source shutdown 2023-11-03 18:58:50,538 DEBUG pyroute2.ndb.140494004154912.sources.localhost/nsmanager:374 sync 2023-11-03 18:58:50,538 DEBUG pyroute2.ndb.140494004154912.sources.localhost/nsmanager:369 shutdown handled by the main thread 2023-11-03 18:58:50,538 DEBUG pyroute2.ndb.140494004154912.sources.localhost/nsmanager:74 stopped 2023-11-03 18:58:50,538 DEBUG pyroute2.ndb.140494004154912.main:851 flush DB for the target localhost/nsmanager 2023-11-03 18:58:50,540 DEBUG subiquitycore.controllers.network:102 default routes True 2023-11-03 18:58:50,540 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'169.254.0.0/16', 'ifindex': 2} 2023-11-03 18:58:50,541 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.1.0/24', 'ifindex': 2} 2023-11-03 18:58:50,541 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'127.0.0.0/8', 'ifindex': 1} 2023-11-03 18:58:50,541 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'127.0.0.1', 'ifindex': 1} 2023-11-03 18:58:50,541 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'127.255.255.255', 'ifindex': 1} 2023-11-03 18:58:50,541 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'192.168.1.116', 'ifindex': 2} 2023-11-03 18:58:50,541 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.1.255', 'ifindex': 2} 2023-11-03 18:58:50,541 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 1, 'table': 254, 'dst': b'::1', 'ifindex': 1} 2023-11-03 18:58:50,541 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 1, 'table': 254, 'dst': b'fe80::/64', 'ifindex': 2} 2023-11-03 18:58:50,541 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 2, 'table': 255, 'dst': b'::1', 'ifindex': 1} 2023-11-03 18:58:50,541 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 2, 'table': 255, 'dst': b'fe80::6662:1ea4:cb32:3ab9', 'ifindex': 2} 2023-11-03 18:58:50,541 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 5, 'table': 255, 'dst': b'ff00::/8', 'ifindex': 2} 2023-11-03 18:58:50,542 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_INTERFACE', 'ifindex': -1} 2023-11-03 18:58:50,542 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_INTERFACE', 'ifindex': 3, 'ssids': []} 2023-11-03 18:58:50,558 DEBUG subiquitycore.core:121 controllers started 2023-11-03 18:58:50,559 INFO root:30 start: subiquity/apply_autoinstall_config: 2023-11-03 18:58:50,559 DEBUG root:30 start: subiquity/Early/apply_autoinstall_config: 2023-11-03 18:58:50,560 DEBUG root:30 finish: subiquity/Early/apply_autoinstall_config: SUCCESS: 2023-11-03 18:58:50,560 DEBUG root:30 start: subiquity/Reporting/apply_autoinstall_config: 2023-11-03 18:58:50,561 DEBUG root:30 finish: subiquity/Reporting/apply_autoinstall_config: SUCCESS: 2023-11-03 18:58:50,561 DEBUG root:30 start: subiquity/Error/apply_autoinstall_config: 2023-11-03 18:58:50,561 DEBUG root:30 finish: subiquity/Error/apply_autoinstall_config: SUCCESS: 2023-11-03 18:58:50,562 DEBUG root:30 start: subiquity/Userdata/apply_autoinstall_config: 2023-11-03 18:58:50,562 DEBUG root:30 finish: subiquity/Userdata/apply_autoinstall_config: SUCCESS: 2023-11-03 18:58:50,563 DEBUG subiquity.models.subiquity:256 model userdata for postinstall stage is configured, to go {'packages', 'ubuntu_pro', 'identity', 'locale', 'ssh', 'drivers', 'snaplist'} 2023-11-03 18:58:50,563 DEBUG root:30 start: subiquity/Package/apply_autoinstall_config: 2023-11-03 18:58:50,563 DEBUG root:30 finish: subiquity/Package/apply_autoinstall_config: SUCCESS: 2023-11-03 18:58:50,563 DEBUG subiquity.models.subiquity:256 model packages for postinstall stage is configured, to go {'ubuntu_pro', 'identity', 'locale', 'ssh', 'drivers', 'snaplist'} 2023-11-03 18:58:50,563 DEBUG root:30 start: subiquity/Debconf/apply_autoinstall_config: 2023-11-03 18:58:50,564 DEBUG root:30 finish: subiquity/Debconf/apply_autoinstall_config: SUCCESS: 2023-11-03 18:58:50,564 DEBUG subiquity.models.subiquity:256 model debconf_selections for install stage is configured, to go {'filesystem', 'proxy', 'keyboard', 'kernel', 'source'} 2023-11-03 18:58:50,565 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Locale as interactive 2023-11-03 18:58:50,565 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Refresh as interactive 2023-11-03 18:58:50,565 DEBUG root:30 start: subiquity/Kernel/apply_autoinstall_config: 2023-11-03 18:58:50,565 DEBUG root:30 finish: subiquity/Kernel/apply_autoinstall_config: SUCCESS: 2023-11-03 18:58:50,565 DEBUG subiquity.models.subiquity:256 model kernel for install stage is configured, to go {'proxy', 'keyboard', 'source', 'filesystem'} 2023-11-03 18:58:50,565 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Integrity as interactive 2023-11-03 18:58:50,565 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Keyboard as interactive 2023-11-03 18:58:50,566 DEBUG root:30 start: subiquity/Zdev/apply_autoinstall_config: 2023-11-03 18:58:50,566 DEBUG root:30 finish: subiquity/Zdev/apply_autoinstall_config: SUCCESS: 2023-11-03 18:58:50,566 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Source as interactive 2023-11-03 18:58:50,566 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Network as interactive 2023-11-03 18:58:50,566 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping UbuntuPro as interactive 2023-11-03 18:58:50,566 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Proxy as interactive 2023-11-03 18:58:50,566 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Mirror as interactive 2023-11-03 18:58:50,566 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Filesystem as interactive 2023-11-03 18:58:50,566 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Identity as interactive 2023-11-03 18:58:50,566 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping SSH as interactive 2023-11-03 18:58:50,567 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping SnapList as interactive 2023-11-03 18:58:50,567 DEBUG root:30 start: subiquity/Ad/apply_autoinstall_config: 2023-11-03 18:58:50,567 DEBUG root:30 finish: subiquity/Ad/apply_autoinstall_config: SUCCESS: 2023-11-03 18:58:50,567 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Codecs as interactive 2023-11-03 18:58:50,567 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Drivers as interactive 2023-11-03 18:58:50,567 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping OEM as interactive 2023-11-03 18:58:50,567 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping TimeZone as interactive 2023-11-03 18:58:50,567 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Install as interactive 2023-11-03 18:58:50,567 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Updates as interactive 2023-11-03 18:58:50,567 DEBUG root:30 start: subiquity/Late/apply_autoinstall_config: 2023-11-03 18:58:50,568 DEBUG root:30 finish: subiquity/Late/apply_autoinstall_config: SUCCESS: 2023-11-03 18:58:50,568 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Shutdown as interactive 2023-11-03 18:58:50,568 INFO root:30 finish: subiquity/apply_autoinstall_config: SUCCESS: 2023-11-03 18:58:50,568 DEBUG subiquity.models.subiquity:256 model locale for postinstall stage is configured, to go {'ubuntu_pro', 'identity', 'ssh', 'drivers', 'snaplist'} 2023-11-03 18:58:50,568 DEBUG root:30 start: subiquity/Refresh/configure_snapd: 2023-11-03 18:58:50,568 DEBUG root:30 start: subiquity/Refresh/configure_snapd/get_details: 2023-11-03 18:58:50,570 DEBUG root:30 start: subiquity/Refresh/check_for_update: 2023-11-03 18:58:50,570 DEBUG root:30 start: subiquity/OEM/load_metapackages_list: 2023-11-03 18:58:50,571 DEBUG root:30 start: subiquity/OEM/load_metapackages_list/wait_confirmation: 2023-11-03 18:58:50,571 DEBUG root:30 start: subiquity/Install/install: 2023-11-03 18:58:50,574 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 18:58:50,588 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/snaps/ubuntu-budgie-installer HTTP/1.1" 200 1573 2023-11-03 18:58:50,597 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 18:58:50,597 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 18:58:50,597 DEBUG subiquity.server.pkghelper:65 checking if wpasupplicant is available 2023-11-03 18:58:52,740 DEBUG subiquity.server.pkghelper:71 wpasupplicant already installed 2023-11-03 18:58:52,741 DEBUG subiquity.server.controllers.snaplist:79 loading list of snaps 2023-11-03 18:58:52,858 DEBUG root:30 start: subiquity/Filesystem/_probe: 2023-11-03 18:58:52,859 DEBUG subiquity.server.controllers.network:112 wlan_support_install_finished PackageInstallState.DONE 2023-11-03 18:58:52,861 DEBUG root:30 start: subiquity/Refresh/check_for_update: 2023-11-03 18:58:52,861 DEBUG root:30 start: subiquity/SnapList/loader: 2023-11-03 18:58:52,862 DEBUG root:30 start: subiquity/Filesystem/_probe/probe_once: restricted=False 2023-11-03 18:58:52,865 DEBUG asyncio:54 Using selector: EpollSelector 2023-11-03 18:58:52,865 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-03 18:58:52,867 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-11-03 18:58:52,868 DEBUG root:30 finish: subiquity/Refresh/configure_snapd/get_details: SUCCESS: current version of snap is: '0+git.b963da6' 2023-11-03 18:58:52,868 DEBUG subiquity.server.controllers.refresh:139 snap tracking , not resetting based on .disk/info 2023-11-03 18:58:52,869 DEBUG root:30 finish: subiquity/Refresh/configure_snapd: SUCCESS: 2023-11-03 18:58:52,869 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 18:58:52,910 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 18:58:52,910 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'HUAWEI-V3FCRP', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Vodafone-UwWV', 'no status')]} 2023-11-03 18:58:52,911 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 18:58:52,919 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 18:58:52,919 DEBUG probert.network:585 event for link_change: CHANGE {'ifindex': 3, 'flags': 4099, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'wlp0s12f0'} 2023-11-03 18:58:52,919 DEBUG probert.network:672 link_change CHANGE {'ifindex': 3, 'flags': 4099, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'wlp0s12f0'} 2023-11-03 18:58:52,919 DEBUG root:30 start: subiquity/Network/_send_update: CHANGE wlp0s12f0 2023-11-03 18:58:52,920 DEBUG root:30 finish: subiquity/Network/_send_update: SUCCESS: CHANGE wlp0s12f0 2023-11-03 18:58:52,920 ERROR root:30 finish: subiquity/Refresh/check_for_update: FAIL: cancelled 2023-11-03 18:58:52,920 DEBUG root:30 start: subiquity/SnapList/loader/list: 2023-11-03 18:58:52,921 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 18:58:52,941 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2023-11-03 18:58:52,943 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2023-11-03 18:58:52,948 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 18:58:52,948 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 18:58:52,950 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 18:58:53,059 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?section=server HTTP/1.1" 200 None 2023-11-03 18:58:53,069 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 18:58:53,070 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'HUAWEI-V3FCRP', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Vodafone-UwWV', 'no status')]} 2023-11-03 18:58:53,073 DEBUG root:30 finish: subiquity/SnapList/loader/list: SUCCESS: 2023-11-03 18:58:53,074 DEBUG subiquity.server.controllers.snaplist:92 fetched list of 22 snaps 2023-11-03 18:58:53,075 DEBUG root:30 start: subiquity/SnapList/loader/fetch/microk8s: 2023-11-03 18:58:53,076 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-03 18:58:53,076 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-11-03 18:58:53,077 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-03 18:58:53,078 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-11-03 18:58:53,079 INFO root:30 start: subiquity/Meta/client_variant_POST: 2023-11-03 18:58:53,079 INFO root:30 finish: subiquity/Meta/client_variant_POST: SUCCESS: 200 null 2023-11-03 18:58:53,089 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-03 18:58:53,095 INFO root:30 start: subiquity/Meta/interactive_sections_GET: 2023-11-03 18:58:53,095 INFO root:30 finish: subiquity/Meta/interactive_sections_GET: SUCCESS: 200 null 2023-11-03 18:58:53,097 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-03 18:58:53,098 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-11-03 18:58:53,101 INFO root:30 start: subiquity/Meta/mark_configured_POST: 2023-11-03 18:58:53,102 DEBUG subiquity.models.subiquity:256 model ubuntu_pro for postinstall stage is configured, to go {'identity', 'network', 'codecs', 'ssh', 'drivers', 'snaplist', 'timezone'} 2023-11-03 18:58:53,102 DEBUG subiquity.models.subiquity:256 model proxy for install stage is configured, to go {'filesystem', 'keyboard', 'network', 'mirror', 'source'} 2023-11-03 18:58:53,103 DEBUG subiquity.models.subiquity:256 model mirror for install stage is configured, to go {'source', 'keyboard', 'filesystem', 'network'} 2023-11-03 18:58:53,103 DEBUG subiquity.models.subiquity:256 model ssh for postinstall stage is configured, to go {'identity', 'network', 'codecs', 'drivers', 'snaplist', 'timezone'} 2023-11-03 18:58:53,103 DEBUG subiquity.models.subiquity:256 model snaplist for postinstall stage is configured, to go {'identity', 'network', 'codecs', 'drivers', 'timezone'} 2023-11-03 18:58:53,103 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null 2023-11-03 18:58:53,105 DEBUG root:30 start: subiquity/Locale/GET: 2023-11-03 18:58:53,106 DEBUG root:30 finish: subiquity/Locale/GET: SUCCESS: 200 "C.UTF-8" 2023-11-03 18:58:53,433 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=microk8s HTTP/1.1" 200 None 2023-11-03 18:58:53,456 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/microk8s: SUCCESS: 2023-11-03 18:58:53,456 DEBUG root:30 start: subiquity/SnapList/loader/fetch/nextcloud: 2023-11-03 18:58:53,462 DEBUG root:30 start: subiquity/Refresh/GET: 2023-11-03 18:58:53,607 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?select=refresh HTTP/1.1" 200 59 2023-11-03 18:58:53,608 DEBUG subiquity.server.controllers.refresh:201 check_for_update received [] 2023-11-03 18:58:53,608 DEBUG root:30 finish: subiquity/Refresh/check_for_update: SUCCESS: no new version of snap available 2023-11-03 18:58:53,609 DEBUG root:30 finish: subiquity/Refresh/GET: SUCCESS: 200 {"availability": "UNAVAILABLE", "current_snap_version": "0+git.b963da6", "new... 2023-11-03 18:58:53,812 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=nextcloud HTTP/1.1" 200 None 2023-11-03 18:58:53,814 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/nextcloud: SUCCESS: 2023-11-03 18:58:53,815 DEBUG root:30 start: subiquity/SnapList/loader/fetch/wekan: 2023-11-03 18:58:54,004 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=wekan HTTP/1.1" 200 None 2023-11-03 18:58:54,006 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/wekan: SUCCESS: 2023-11-03 18:58:54,007 DEBUG root:30 start: subiquity/SnapList/loader/fetch/kata-containers: 2023-11-03 18:58:54,021 ERROR probert.raid:40 Failed mdadm_assemble, mdadm command not found: [Errno 2] No such file or directory: 'mdadm' 2023-11-03 18:58:54,226 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=kata-containers HTTP/1.1" 200 None 2023-11-03 18:58:54,228 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/kata-containers: SUCCESS: 2023-11-03 18:58:54,235 DEBUG root:30 start: subiquity/SnapList/loader/fetch/docker: 2023-11-03 18:58:54,402 DEBUG probert.dasd:134 Probing DASD devies 2023-11-03 18:58:54,403 DEBUG probert.dasd:137 DASD devices only present on s390x, arch=x86_64 2023-11-03 18:58:54,424 DEBUG probert.utils:85 Command `/usr/sbin/dumpe2fs -h /dev/sda2` exited with result: 0 2023-11-03 18:58:54,424 DEBUG probert.utils:48 stdout: ------------------------------------------ 2023-11-03 18:58:54,424 DEBUG probert.utils:50 Filesystem volume name: 2023-11-03 18:58:54,424 DEBUG probert.utils:50 Last mounted on: /target 2023-11-03 18:58:54,424 DEBUG probert.utils:50 Filesystem UUID: 91bf2730-b145-4993-8ae4-2838e60cf3e8 2023-11-03 18:58:54,425 DEBUG probert.utils:50 Filesystem magic number: 0xEF53 2023-11-03 18:58:54,425 DEBUG probert.utils:50 Filesystem revision #: 1 (dynamic) 2023-11-03 18:58:54,425 DEBUG probert.utils:50 Filesystem features: has_journal ext_attr resize_inode dir_index filetype extent 64bit flex_bg sparse_super large_file huge_file dir_nlink extra_isize metadata_csum 2023-11-03 18:58:54,425 DEBUG probert.utils:50 Filesystem flags: signed_directory_hash 2023-11-03 18:58:54,425 DEBUG probert.utils:50 Default mount options: user_xattr acl 2023-11-03 18:58:54,425 DEBUG probert.utils:50 Filesystem state: clean 2023-11-03 18:58:54,425 DEBUG probert.utils:50 Errors behavior: Continue 2023-11-03 18:58:54,425 DEBUG probert.utils:50 Filesystem OS type: Linux 2023-11-03 18:58:54,425 DEBUG probert.utils:50 Inode count: 7749632 2023-11-03 18:58:54,425 DEBUG probert.utils:50 Block count: 30982912 2023-11-03 18:58:54,425 DEBUG probert.utils:50 Reserved block count: 1549145 2023-11-03 18:58:54,425 DEBUG probert.utils:50 Overhead clusters: 632916 2023-11-03 18:58:54,425 DEBUG probert.utils:50 Free blocks: 28117342 2023-11-03 18:58:54,426 DEBUG probert.utils:50 Free inodes: 7561202 2023-11-03 18:58:54,426 DEBUG probert.utils:50 First block: 0 2023-11-03 18:58:54,426 DEBUG probert.utils:50 Block size: 4096 2023-11-03 18:58:54,426 DEBUG probert.utils:50 Fragment size: 4096 2023-11-03 18:58:54,426 DEBUG probert.utils:50 Group descriptor size: 64 2023-11-03 18:58:54,426 DEBUG probert.utils:50 Reserved GDT blocks: 1024 2023-11-03 18:58:54,426 DEBUG probert.utils:50 Blocks per group: 32768 2023-11-03 18:58:54,426 DEBUG probert.utils:50 Fragments per group: 32768 2023-11-03 18:58:54,426 DEBUG probert.utils:50 Inodes per group: 8192 2023-11-03 18:58:54,426 DEBUG probert.utils:50 Inode blocks per group: 512 2023-11-03 18:58:54,426 DEBUG probert.utils:50 Flex block group size: 16 2023-11-03 18:58:54,426 DEBUG probert.utils:50 Filesystem created: Fri Nov 3 16:52:56 2023 2023-11-03 18:58:54,426 DEBUG probert.utils:50 Last mount time: Fri Nov 3 16:53:00 2023 2023-11-03 18:58:54,427 DEBUG probert.utils:50 Last write time: Fri Nov 3 17:12:57 2023 2023-11-03 18:58:54,427 DEBUG probert.utils:50 Mount count: 1 2023-11-03 18:58:54,427 DEBUG probert.utils:50 Maximum mount count: -1 2023-11-03 18:58:54,427 DEBUG probert.utils:50 Last checked: Fri Nov 3 16:52:56 2023 2023-11-03 18:58:54,427 DEBUG probert.utils:50 Check interval: 0 () 2023-11-03 18:58:54,427 DEBUG probert.utils:50 Lifetime writes: 7888 MB 2023-11-03 18:58:54,427 DEBUG probert.utils:50 Reserved blocks uid: 0 (user root) 2023-11-03 18:58:54,427 DEBUG probert.utils:50 Reserved blocks gid: 0 (group root) 2023-11-03 18:58:54,427 DEBUG probert.utils:50 First inode: 11 2023-11-03 18:58:54,427 DEBUG probert.utils:50 Inode size: 256 2023-11-03 18:58:54,427 DEBUG probert.utils:50 Required extra isize: 32 2023-11-03 18:58:54,427 DEBUG probert.utils:50 Desired extra isize: 32 2023-11-03 18:58:54,427 DEBUG probert.utils:50 Journal inode: 8 2023-11-03 18:58:54,428 DEBUG probert.utils:50 Default directory hash: half_md4 2023-11-03 18:58:54,428 DEBUG probert.utils:50 Directory Hash Seed: 838241d0-31ab-4609-aa76-83ba52fecfb2 2023-11-03 18:58:54,428 DEBUG probert.utils:50 Journal backup: inode blocks 2023-11-03 18:58:54,428 DEBUG probert.utils:50 Checksum type: crc32c 2023-11-03 18:58:54,428 DEBUG probert.utils:50 Checksum: 0x87589456 2023-11-03 18:58:54,428 DEBUG probert.utils:50 Journal features: journal_incompat_revoke journal_64bit journal_checksum_v3 2023-11-03 18:58:54,428 DEBUG probert.utils:50 Total journal size: 512M 2023-11-03 18:58:54,428 DEBUG probert.utils:50 Total journal blocks: 131072 2023-11-03 18:58:54,428 DEBUG probert.utils:50 Max transaction length: 131072 2023-11-03 18:58:54,428 DEBUG probert.utils:50 Fast commit length: 0 2023-11-03 18:58:54,428 DEBUG probert.utils:50 Journal sequence: 0x00000418 2023-11-03 18:58:54,428 DEBUG probert.utils:50 Journal start: 0 2023-11-03 18:58:54,429 DEBUG probert.utils:50 Journal checksum type: crc32c 2023-11-03 18:58:54,429 DEBUG probert.utils:50 Journal checksum: 0x27a65182 2023-11-03 18:58:54,429 DEBUG probert.utils:50 2023-11-03 18:58:54,429 DEBUG probert.utils:48 stderr: ------------------------------------------ 2023-11-03 18:58:54,429 DEBUG probert.utils:50 dumpe2fs 1.47.0 (5-Feb-2023) 2023-11-03 18:58:54,429 DEBUG probert.utils:88 -------------------------------------------------- 2023-11-03 18:58:54,438 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=docker HTTP/1.1" 200 None 2023-11-03 18:58:54,439 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/docker: SUCCESS: 2023-11-03 18:58:54,440 DEBUG root:30 start: subiquity/SnapList/loader/fetch/canonical-livepatch: 2023-11-03 18:58:54,441 DEBUG probert.utils:85 Command `/usr/sbin/resize2fs -P /dev/sda2` exited with result: 0 2023-11-03 18:58:54,443 DEBUG probert.utils:48 stdout: ------------------------------------------ 2023-11-03 18:58:54,443 DEBUG probert.utils:50 Estimated minimum size of the filesystem: 2726783 2023-11-03 18:58:54,443 DEBUG probert.utils:48 stderr: ------------------------------------------ 2023-11-03 18:58:54,443 DEBUG probert.utils:50 resize2fs 1.47.0 (5-Feb-2023) 2023-11-03 18:58:54,443 DEBUG probert.utils:88 -------------------------------------------------- 2023-11-03 18:58:54,447 DEBUG probert.utils:85 Command `/usr/sbin/dumpe2fs -h /dev/sdb4` exited with result: 0 2023-11-03 18:58:54,447 DEBUG probert.utils:48 stdout: ------------------------------------------ 2023-11-03 18:58:54,447 DEBUG probert.utils:50 Filesystem volume name: writable 2023-11-03 18:58:54,447 DEBUG probert.utils:50 Last mounted on: /root/var/crash 2023-11-03 18:58:54,447 DEBUG probert.utils:50 Filesystem UUID: fa4eb7d9-9bab-4738-867e-856a933c64a6 2023-11-03 18:58:54,448 DEBUG probert.utils:50 Filesystem magic number: 0xEF53 2023-11-03 18:58:54,448 DEBUG probert.utils:50 Filesystem revision #: 1 (dynamic) 2023-11-03 18:58:54,448 DEBUG probert.utils:50 Filesystem features: has_journal ext_attr resize_inode dir_index filetype needs_recovery extent 64bit flex_bg sparse_super large_file huge_file dir_nlink extra_isize metadata_csum 2023-11-03 18:58:54,448 DEBUG probert.utils:50 Filesystem flags: signed_directory_hash 2023-11-03 18:58:54,448 DEBUG probert.utils:50 Default mount options: user_xattr acl 2023-11-03 18:58:54,448 DEBUG probert.utils:50 Filesystem state: clean 2023-11-03 18:58:54,448 DEBUG probert.utils:50 Errors behavior: Continue 2023-11-03 18:58:54,448 DEBUG probert.utils:50 Filesystem OS type: Linux 2023-11-03 18:58:54,448 DEBUG probert.utils:50 Inode count: 1695744 2023-11-03 18:58:54,448 DEBUG probert.utils:50 Block count: 6779136 2023-11-03 18:58:54,448 DEBUG probert.utils:50 Reserved block count: 338956 2023-11-03 18:58:54,448 DEBUG probert.utils:50 Overhead clusters: 150485 2023-11-03 18:58:54,449 DEBUG probert.utils:50 Free blocks: 6627755 2023-11-03 18:58:54,449 DEBUG probert.utils:50 Free inodes: 1695686 2023-11-03 18:58:54,449 DEBUG probert.utils:50 First block: 0 2023-11-03 18:58:54,449 DEBUG probert.utils:50 Block size: 4096 2023-11-03 18:58:54,449 DEBUG probert.utils:50 Fragment size: 4096 2023-11-03 18:58:54,449 DEBUG probert.utils:50 Group descriptor size: 64 2023-11-03 18:58:54,449 DEBUG probert.utils:50 Reserved GDT blocks: 1024 2023-11-03 18:58:54,449 DEBUG probert.utils:50 Blocks per group: 32768 2023-11-03 18:58:54,449 DEBUG probert.utils:50 Fragments per group: 32768 2023-11-03 18:58:54,449 DEBUG probert.utils:50 Inodes per group: 8192 2023-11-03 18:58:54,449 DEBUG probert.utils:50 Inode blocks per group: 512 2023-11-03 18:58:54,449 DEBUG probert.utils:50 Flex block group size: 16 2023-11-03 18:58:54,449 DEBUG probert.utils:50 Filesystem created: Fri Nov 3 16:46:32 2023 2023-11-03 18:58:54,450 DEBUG probert.utils:50 Last mount time: Fri Nov 3 18:57:43 2023 2023-11-03 18:58:54,450 DEBUG probert.utils:50 Last write time: Fri Nov 3 18:57:43 2023 2023-11-03 18:58:54,450 DEBUG probert.utils:50 Mount count: 3 2023-11-03 18:58:54,450 DEBUG probert.utils:50 Maximum mount count: -1 2023-11-03 18:58:54,450 DEBUG probert.utils:50 Last checked: Fri Nov 3 16:46:32 2023 2023-11-03 18:58:54,450 DEBUG probert.utils:50 Check interval: 0 () 2023-11-03 18:58:54,450 DEBUG probert.utils:50 Lifetime writes: 4306 kB 2023-11-03 18:58:54,450 DEBUG probert.utils:50 Reserved blocks uid: 0 (user root) 2023-11-03 18:58:54,450 DEBUG probert.utils:50 Reserved blocks gid: 0 (group root) 2023-11-03 18:58:54,450 DEBUG probert.utils:50 First inode: 11 2023-11-03 18:58:54,450 DEBUG probert.utils:50 Inode size: 256 2023-11-03 18:58:54,450 DEBUG probert.utils:50 Required extra isize: 32 2023-11-03 18:58:54,450 DEBUG probert.utils:50 Desired extra isize: 32 2023-11-03 18:58:54,451 DEBUG probert.utils:50 Journal inode: 8 2023-11-03 18:58:54,451 DEBUG probert.utils:50 Default directory hash: half_md4 2023-11-03 18:58:54,451 DEBUG probert.utils:50 Directory Hash Seed: b2e8127b-75c1-4ba7-ad76-5af913ada5e2 2023-11-03 18:58:54,451 DEBUG probert.utils:50 Journal backup: inode blocks 2023-11-03 18:58:54,451 DEBUG probert.utils:50 Checksum type: crc32c 2023-11-03 18:58:54,451 DEBUG probert.utils:50 Checksum: 0x6f579ba2 2023-11-03 18:58:54,451 DEBUG probert.utils:50 Journal features: journal_64bit journal_checksum_v3 2023-11-03 18:58:54,451 DEBUG probert.utils:50 Total journal size: 128M 2023-11-03 18:58:54,451 DEBUG probert.utils:50 Total journal blocks: 32768 2023-11-03 18:58:54,451 DEBUG probert.utils:50 Max transaction length: 32768 2023-11-03 18:58:54,451 DEBUG probert.utils:50 Fast commit length: 0 2023-11-03 18:58:54,451 DEBUG probert.utils:50 Journal sequence: 0x00000098 2023-11-03 18:58:54,451 DEBUG probert.utils:50 Journal start: 829 2023-11-03 18:58:54,452 DEBUG probert.utils:50 Journal checksum type: crc32c 2023-11-03 18:58:54,452 DEBUG probert.utils:50 Journal checksum: 0x30aa13fe 2023-11-03 18:58:54,452 DEBUG probert.utils:50 2023-11-03 18:58:54,452 DEBUG probert.utils:48 stderr: ------------------------------------------ 2023-11-03 18:58:54,452 DEBUG probert.utils:50 dumpe2fs 1.47.0 (5-Feb-2023) 2023-11-03 18:58:54,452 DEBUG probert.utils:88 -------------------------------------------------- 2023-11-03 18:58:54,461 DEBUG probert.utils:85 Command `/usr/sbin/resize2fs -P /dev/sdb4` exited with result: 0 2023-11-03 18:58:54,462 DEBUG probert.utils:48 stdout: ------------------------------------------ 2023-11-03 18:58:54,462 DEBUG probert.utils:50 Estimated minimum size of the filesystem: 49871 2023-11-03 18:58:54,462 DEBUG probert.utils:48 stderr: ------------------------------------------ 2023-11-03 18:58:54,462 DEBUG probert.utils:50 resize2fs 1.47.0 (5-Feb-2023) 2023-11-03 18:58:54,462 DEBUG probert.utils:88 -------------------------------------------------- 2023-11-03 18:58:54,638 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=canonical-livepatch HTTP/1.1" 200 None 2023-11-03 18:58:54,641 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/canonical-livepatch: SUCCESS: 2023-11-03 18:58:54,641 DEBUG root:30 start: subiquity/SnapList/loader/fetch/rocketchat-server: 2023-11-03 18:58:54,848 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=rocketchat-server HTTP/1.1" 200 None 2023-11-03 18:58:54,850 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/rocketchat-server: SUCCESS: 2023-11-03 18:58:54,850 DEBUG root:30 start: subiquity/SnapList/loader/fetch/mosquitto: 2023-11-03 18:58:55,051 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=mosquitto HTTP/1.1" 200 None 2023-11-03 18:58:55,053 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/mosquitto: SUCCESS: 2023-11-03 18:58:55,053 DEBUG root:30 start: subiquity/SnapList/loader/fetch/etcd: 2023-11-03 18:58:55,263 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=etcd HTTP/1.1" 200 None 2023-11-03 18:58:55,266 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/etcd: SUCCESS: 2023-11-03 18:58:55,266 DEBUG root:30 start: subiquity/SnapList/loader/fetch/powershell: 2023-11-03 18:58:55,477 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=powershell HTTP/1.1" 200 None 2023-11-03 18:58:55,479 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/powershell: SUCCESS: 2023-11-03 18:58:55,479 DEBUG root:30 start: subiquity/SnapList/loader/fetch/sabnzbd: 2023-11-03 18:58:55,633 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=sabnzbd HTTP/1.1" 200 None 2023-11-03 18:58:55,635 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/sabnzbd: SUCCESS: 2023-11-03 18:58:55,635 DEBUG root:30 start: subiquity/SnapList/loader/fetch/wormhole: 2023-11-03 18:58:55,803 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=wormhole HTTP/1.1" 200 None 2023-11-03 18:58:55,805 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/wormhole: SUCCESS: 2023-11-03 18:58:55,805 DEBUG root:30 start: subiquity/SnapList/loader/fetch/aws-cli: 2023-11-03 18:58:55,964 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=aws-cli HTTP/1.1" 200 None 2023-11-03 18:58:55,966 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/aws-cli: SUCCESS: 2023-11-03 18:58:55,966 DEBUG root:30 start: subiquity/SnapList/loader/fetch/google-cloud-sdk: 2023-11-03 18:58:56,082 DEBUG curtin:1325 Extracting storage config from probe data 2023-11-03 18:58:56,083 DEBUG curtin:73 /dev/sda is multipath device member? False 2023-11-03 18:58:56,083 DEBUG curtin:86 /dev/sda is multipath device partition? False 2023-11-03 18:58:56,083 DEBUG curtin:61 /dev/sda is multipath device? False 2023-11-03 18:58:56,083 DEBUG curtin:86 /dev/sda is multipath device partition? False 2023-11-03 18:58:56,083 DEBUG curtin:61 /dev/sda is multipath device? False 2023-11-03 18:58:56,109 DEBUG curtin:73 /dev/sda1 is multipath device member? False 2023-11-03 18:58:56,109 DEBUG curtin:86 /dev/sda1 is multipath device partition? False 2023-11-03 18:58:56,109 DEBUG curtin:61 /dev/sda1 is multipath device? False 2023-11-03 18:58:56,110 DEBUG curtin:86 /dev/sda1 is multipath device partition? False 2023-11-03 18:58:56,110 DEBUG curtin:86 /dev/sda1 is multipath device partition? False 2023-11-03 18:58:56,134 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=google-cloud-sdk HTTP/1.1" 200 1799 2023-11-03 18:58:56,134 DEBUG curtin:73 /dev/sda2 is multipath device member? False 2023-11-03 18:58:56,135 DEBUG curtin:86 /dev/sda2 is multipath device partition? False 2023-11-03 18:58:56,135 DEBUG curtin:61 /dev/sda2 is multipath device? False 2023-11-03 18:58:56,135 DEBUG curtin:86 /dev/sda2 is multipath device partition? False 2023-11-03 18:58:56,135 DEBUG curtin:86 /dev/sda2 is multipath device partition? False 2023-11-03 18:58:56,160 DEBUG curtin:73 /dev/sdb is multipath device member? False 2023-11-03 18:58:56,160 DEBUG curtin:86 /dev/sdb is multipath device partition? False 2023-11-03 18:58:56,160 DEBUG curtin:61 /dev/sdb is multipath device? False 2023-11-03 18:58:56,160 DEBUG curtin:86 /dev/sdb is multipath device partition? False 2023-11-03 18:58:56,161 DEBUG curtin:61 /dev/sdb is multipath device? False 2023-11-03 18:58:56,186 DEBUG curtin:73 /dev/sdb1 is multipath device member? False 2023-11-03 18:58:56,187 DEBUG curtin:86 /dev/sdb1 is multipath device partition? False 2023-11-03 18:58:56,187 DEBUG curtin:61 /dev/sdb1 is multipath device? False 2023-11-03 18:58:56,187 DEBUG curtin:86 /dev/sdb1 is multipath device partition? False 2023-11-03 18:58:56,187 DEBUG curtin:86 /dev/sdb1 is multipath device partition? False 2023-11-03 18:58:56,212 DEBUG curtin:73 /dev/sdb2 is multipath device member? False 2023-11-03 18:58:56,213 DEBUG curtin:86 /dev/sdb2 is multipath device partition? False 2023-11-03 18:58:56,213 DEBUG curtin:61 /dev/sdb2 is multipath device? False 2023-11-03 18:58:56,213 DEBUG curtin:86 /dev/sdb2 is multipath device partition? False 2023-11-03 18:58:56,213 DEBUG curtin:86 /dev/sdb2 is multipath device partition? False 2023-11-03 18:58:56,240 DEBUG curtin:73 /dev/sdb3 is multipath device member? False 2023-11-03 18:58:56,240 DEBUG curtin:86 /dev/sdb3 is multipath device partition? False 2023-11-03 18:58:56,240 DEBUG curtin:61 /dev/sdb3 is multipath device? False 2023-11-03 18:58:56,240 DEBUG curtin:86 /dev/sdb3 is multipath device partition? False 2023-11-03 18:58:56,240 DEBUG curtin:86 /dev/sdb3 is multipath device partition? False 2023-11-03 18:58:56,266 DEBUG curtin:73 /dev/sdb4 is multipath device member? False 2023-11-03 18:58:56,266 DEBUG curtin:86 /dev/sdb4 is multipath device partition? False 2023-11-03 18:58:56,267 DEBUG curtin:61 /dev/sdb4 is multipath device? False 2023-11-03 18:58:56,267 DEBUG curtin:86 /dev/sdb4 is multipath device partition? False 2023-11-03 18:58:56,267 DEBUG curtin:86 /dev/sdb4 is multipath device partition? False 2023-11-03 18:58:56,291 DEBUG curtin:73 /dev/sda1 is multipath device member? False 2023-11-03 18:58:56,317 DEBUG curtin:73 /dev/sdb is multipath device member? False 2023-11-03 18:58:56,343 DEBUG curtin:73 /dev/sdb1 is multipath device member? False 2023-11-03 18:58:56,368 DEBUG curtin:73 /dev/sdb2 is multipath device member? False 2023-11-03 18:58:56,393 DEBUG curtin:73 /dev/sda2 is multipath device member? False 2023-11-03 18:58:56,418 DEBUG curtin:73 /dev/sdb4 is multipath device member? False 2023-11-03 18:58:56,469 DEBUG curtin:1332 Sorting extracted configurations 2023-11-03 18:58:56,470 INFO curtin:1351 Validating extracted storage config components 2023-11-03 18:58:56,518 DEBUG curtin:1368 Extracted (unmerged) storage config: storage: - id: disk-sda path: /dev/sda ptable: gpt serial: FORESEE_128GB_SSD_K44700J003729 type: disk wwn: '0x502b2a201d1c1b1a' - id: disk-sdb path: /dev/sdb ptable: gpt serial: Kingston_DataTraveler_3.0_40B0767E23131571065372E0-0:0 type: disk - device: disk-sda flag: boot id: partition-sda1 number: 1 offset: 1048576 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sda1 size: 1127219200 type: partition uuid: 908d9db1-e4e0-4a91-9730-a327cffdc1aa - device: disk-sda flag: linux id: partition-sda2 number: 2 offset: 1128267776 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sda2 size: 126906007552 type: partition uuid: 97307bf8-717e-4bdf-8089-fa1216a7ff2a - device: disk-sdb id: partition-sdb1 number: 1 offset: 32768 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb1 size: 3221395456 type: partition uuid: 23a89eda-eaed-4b01-bef3-0d031403b683 - device: disk-sdb flag: boot id: partition-sdb2 number: 2 offset: 3221428224 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sdb2 size: 5138432 type: partition uuid: 23a89eda-eaed-4b01-bef0-0d031403b683 - device: disk-sdb id: partition-sdb3 number: 3 offset: 3226566656 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb3 size: 307200 type: partition uuid: 23a89eda-eaed-4b01-bef1-0d031403b683 - device: disk-sdb flag: linux id: partition-sdb4 number: 4 offset: 3227516928 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sdb4 size: 27767341056 type: partition uuid: 950f9560-a726-484c-af82-45566c41cae8 - fstype: vfat id: format-partition-sda1 type: format volume: partition-sda1 - fstype: iso9660 id: format-disk-sdb type: format volume: disk-sdb - fstype: iso9660 id: format-partition-sdb1 type: format volume: partition-sdb1 - fstype: vfat id: format-partition-sdb2 type: format volume: partition-sdb2 - fstype: ext4 id: format-partition-sda2 type: format uuid: 91bf2730-b145-4993-8ae4-2838e60cf3e8 volume: partition-sda2 - fstype: ext4 id: format-partition-sdb4 type: format uuid: fa4eb7d9-9bab-4738-867e-856a933c64a6 volume: partition-sdb4 - device: format-partition-sdb1 id: mount-partition-sdb1 path: /cdrom type: mount 2023-11-03 18:58:56,518 DEBUG curtin:1372 Generating storage config dependencies 2023-11-03 18:58:56,518 DEBUG curtin:238 Validate: partition-sda1:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:58:56,518 DEBUG curtin:238 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:58:56,519 DEBUG curtin:238 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:58:56,519 DEBUG curtin:238 Validate: partition-sdb2:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:58:56,519 DEBUG curtin:238 Validate: partition-sdb3:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:58:56,519 DEBUG curtin:238 Validate: partition-sdb4:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:58:56,519 DEBUG curtin:238 Validate: format-partition-sda1:SourceType:format -> (DepId:partition-sda1 DepType:partition) in SourceDeps:{'disk', 'bcache', 'lvm_partition', 'raid', 'partition', 'dm_crypt'} ? result=True 2023-11-03 18:58:56,519 DEBUG curtin:238 Validate: partition-sda1:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:58:56,519 DEBUG curtin:238 Validate: format-disk-sdb:SourceType:format -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'bcache', 'lvm_partition', 'raid', 'partition', 'dm_crypt'} ? result=True 2023-11-03 18:58:56,520 DEBUG curtin:238 Validate: format-partition-sdb1:SourceType:format -> (DepId:partition-sdb1 DepType:partition) in SourceDeps:{'disk', 'bcache', 'lvm_partition', 'raid', 'partition', 'dm_crypt'} ? result=True 2023-11-03 18:58:56,520 DEBUG curtin:238 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:58:56,520 DEBUG curtin:238 Validate: format-partition-sdb2:SourceType:format -> (DepId:partition-sdb2 DepType:partition) in SourceDeps:{'disk', 'bcache', 'lvm_partition', 'raid', 'partition', 'dm_crypt'} ? result=True 2023-11-03 18:58:56,520 DEBUG curtin:238 Validate: partition-sdb2:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:58:56,520 DEBUG curtin:238 Validate: format-partition-sda2:SourceType:format -> (DepId:partition-sda2 DepType:partition) in SourceDeps:{'disk', 'bcache', 'lvm_partition', 'raid', 'partition', 'dm_crypt'} ? result=True 2023-11-03 18:58:56,520 DEBUG curtin:238 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:58:56,520 DEBUG curtin:238 Validate: format-partition-sdb4:SourceType:format -> (DepId:partition-sdb4 DepType:partition) in SourceDeps:{'disk', 'bcache', 'lvm_partition', 'raid', 'partition', 'dm_crypt'} ? result=True 2023-11-03 18:58:56,520 DEBUG curtin:238 Validate: partition-sdb4:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:58:56,521 DEBUG curtin:238 Validate: mount-partition-sdb1:SourceType:mount -> (DepId:format-partition-sdb1 DepType:format) in SourceDeps:{'format'} ? result=True 2023-11-03 18:58:56,521 DEBUG curtin:238 Validate: format-partition-sdb1:SourceType:format -> (DepId:partition-sdb1 DepType:partition) in SourceDeps:{'disk', 'bcache', 'lvm_partition', 'raid', 'partition', 'dm_crypt'} ? result=True 2023-11-03 18:58:56,521 DEBUG curtin:238 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:58:56,521 DEBUG curtin:1378 Merging storage config dependencies 2023-11-03 18:58:56,533 DEBUG curtin:1383 Merged storage config: storage: config: - id: disk-sda path: /dev/sda ptable: gpt serial: FORESEE_128GB_SSD_K44700J003729 type: disk wwn: '0x502b2a201d1c1b1a' - id: disk-sdb path: /dev/sdb ptable: gpt serial: Kingston_DataTraveler_3.0_40B0767E23131571065372E0-0:0 type: disk - fstype: iso9660 id: format-disk-sdb type: format volume: disk-sdb - device: disk-sda flag: boot id: partition-sda1 number: 1 offset: 1048576 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sda1 size: 1127219200 type: partition uuid: 908d9db1-e4e0-4a91-9730-a327cffdc1aa - device: disk-sda flag: linux id: partition-sda2 number: 2 offset: 1128267776 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sda2 size: 126906007552 type: partition uuid: 97307bf8-717e-4bdf-8089-fa1216a7ff2a - fstype: vfat id: format-partition-sda1 type: format volume: partition-sda1 - fstype: ext4 id: format-partition-sda2 type: format uuid: 91bf2730-b145-4993-8ae4-2838e60cf3e8 volume: partition-sda2 - device: disk-sdb id: partition-sdb1 number: 1 offset: 32768 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb1 size: 3221395456 type: partition uuid: 23a89eda-eaed-4b01-bef3-0d031403b683 - device: disk-sdb flag: boot id: partition-sdb2 number: 2 offset: 3221428224 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sdb2 size: 5138432 type: partition uuid: 23a89eda-eaed-4b01-bef0-0d031403b683 - device: disk-sdb id: partition-sdb3 number: 3 offset: 3226566656 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb3 size: 307200 type: partition uuid: 23a89eda-eaed-4b01-bef1-0d031403b683 - device: disk-sdb flag: linux id: partition-sdb4 number: 4 offset: 3227516928 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sdb4 size: 27767341056 type: partition uuid: 950f9560-a726-484c-af82-45566c41cae8 - fstype: iso9660 id: format-partition-sdb1 type: format volume: partition-sdb1 - fstype: vfat id: format-partition-sdb2 type: format volume: partition-sdb2 - fstype: ext4 id: format-partition-sdb4 type: format uuid: fa4eb7d9-9bab-4738-867e-856a933c64a6 volume: partition-sdb4 - device: format-partition-sdb1 id: mount-partition-sdb1 path: /cdrom type: mount version: 2 2023-11-03 18:58:56,534 DEBUG subiquity.models.filesystem:1463 majmin_to_dev {'8:0': Disk(ptable='gpt', serial='FORESEE_128GB_SSD_K44700J003729', wwn='0x502b2a201d1c1b1a', path='/dev/sda', preserve=True, id='disk-sda', type='disk'), '8:16': Disk(ptable='gpt', serial='Kingston_DataTraveler_3.0_40B0767E23131571065372E0-0:0', path='/dev/sdb', preserve=True, id='disk-sdb', type='disk'), '8:1': Partition(device=disk-sda, size=1127219200, flag='boot', number=1, preserve=True, offset=1048576, partition_type='c12a7328-f81f-11d2-ba4b-00a0c93ec93b', path='/dev/sda1', uuid='908d9db1-e4e0-4a91-9730-a327cffdc1aa', id='partition-sda1', type='partition'), '8:2': Partition(device=disk-sda, size=126906007552, flag='linux', number=2, preserve=True, offset=1128267776, partition_type='0fc63daf-8483-4772-8e79-3d69d8477de4', path='/dev/sda2', uuid='97307bf8-717e-4bdf-8089-fa1216a7ff2a', id='partition-sda2', type='partition'), '8:17': Partition(device=disk-sdb, size=3221395456, number=1, preserve=True, offset=32768, partition_type='ebd0a0a2-b9e5-4433-87c0-68b6b72699c7', path='/dev/sdb1', uuid='23a89eda-eaed-4b01-bef3-0d031403b683', id='partition-sdb1', type='partition'), '8:18': Partition(device=disk-sdb, size=5138432, flag='boot', number=2, preserve=True, offset=3221428224, partition_type='c12a7328-f81f-11d2-ba4b-00a0c93ec93b', path='/dev/sdb2', uuid='23a89eda-eaed-4b01-bef0-0d031403b683', id='partition-sdb2', type='partition'), '8:19': Partition(device=disk-sdb, size=307200, number=3, preserve=True, offset=3226566656, partition_type='ebd0a0a2-b9e5-4433-87c0-68b6b72699c7', path='/dev/sdb3', uuid='23a89eda-eaed-4b01-bef1-0d031403b683', id='partition-sdb3', type='partition'), '8:20': Partition(device=disk-sdb, size=27767341056, flag='linux', number=4, preserve=True, offset=3227516928, partition_type='0fc63daf-8483-4772-8e79-3d69d8477de4', path='/dev/sdb4', uuid='950f9560-a726-484c-af82-45566c41cae8', id='partition-sdb4', type='partition')} 2023-11-03 18:58:56,534 DEBUG subiquity.models.filesystem:1475 considering mount of 0:28 2023-11-03 18:58:56,534 DEBUG subiquity.models.filesystem:1475 considering mount of 0:22 2023-11-03 18:58:56,534 DEBUG subiquity.models.filesystem:1475 considering mount of 0:23 2023-11-03 18:58:56,534 DEBUG subiquity.models.filesystem:1475 considering mount of 0:5 2023-11-03 18:58:56,534 DEBUG subiquity.models.filesystem:1475 considering mount of 0:25 2023-11-03 18:58:56,534 DEBUG subiquity.models.filesystem:1475 considering mount of 8:17 2023-11-03 18:58:56,535 DEBUG subiquity.models.filesystem:1480 /dev/sdb1 is mounted 2023-11-03 18:58:56,535 DEBUG subiquity.models.filesystem:1475 considering mount of 7:0 2023-11-03 18:58:56,535 DEBUG subiquity.models.filesystem:1475 considering mount of 8:20 2023-11-03 18:58:56,535 DEBUG subiquity.models.filesystem:1480 /dev/sdb4 is mounted 2023-11-03 18:58:56,535 DEBUG subiquity.models.filesystem:1475 considering mount of 8:20 2023-11-03 18:58:56,535 DEBUG subiquity.models.filesystem:1480 /dev/sdb4 is mounted 2023-11-03 18:58:56,535 DEBUG subiquity.models.filesystem:1475 considering mount of 0:28 2023-11-03 18:58:56,535 DEBUG subiquity.models.filesystem:1475 considering mount of 0:45 2023-11-03 18:58:56,535 DEBUG subiquity.models.filesystem:1475 considering mount of 7:4 2023-11-03 18:58:56,535 DEBUG subiquity.models.filesystem:1475 considering mount of 7:5 2023-11-03 18:58:56,535 DEBUG subiquity.models.filesystem:1475 considering mount of 7:3 2023-11-03 18:58:56,535 DEBUG subiquity.models.filesystem:1475 considering mount of 7:6 2023-11-03 18:58:56,535 DEBUG subiquity.models.filesystem:1475 considering mount of 7:11 2023-11-03 18:58:56,536 DEBUG subiquity.models.filesystem:1475 considering mount of 7:7 2023-11-03 18:58:56,536 DEBUG subiquity.models.filesystem:1475 considering mount of 7:8 2023-11-03 18:58:56,536 DEBUG subiquity.models.filesystem:1475 considering mount of 7:9 2023-11-03 18:58:56,536 DEBUG subiquity.models.filesystem:1475 considering mount of 7:10 2023-11-03 18:58:56,536 DEBUG subiquity.models.filesystem:1475 considering mount of 0:28 2023-11-03 18:58:56,536 DEBUG subiquity.models.filesystem:1475 considering mount of 0:6 2023-11-03 18:58:56,536 DEBUG subiquity.models.filesystem:1475 considering mount of 0:35 2023-11-03 18:58:56,536 DEBUG subiquity.models.filesystem:1475 considering mount of 0:36 2023-11-03 18:58:56,536 DEBUG subiquity.models.filesystem:1475 considering mount of 0:37 2023-11-03 18:58:56,536 DEBUG subiquity.models.filesystem:1475 considering mount of 0:38 2023-11-03 18:58:56,536 DEBUG subiquity.models.filesystem:1475 considering mount of 0:12 2023-11-03 18:58:56,536 DEBUG subiquity.models.filesystem:1475 considering mount of 0:7 2023-11-03 18:58:56,536 DEBUG subiquity.models.filesystem:1475 considering mount of 0:41 2023-11-03 18:58:56,536 DEBUG subiquity.models.filesystem:1475 considering mount of 0:21 2023-11-03 18:58:56,536 DEBUG subiquity.models.filesystem:1475 considering mount of 0:39 2023-11-03 18:58:56,536 DEBUG subiquity.models.filesystem:1475 considering mount of 0:24 2023-11-03 18:58:56,537 DEBUG subiquity.models.filesystem:1475 considering mount of 0:33 2023-11-03 18:58:56,537 DEBUG subiquity.models.filesystem:1475 considering mount of 0:20 2023-11-03 18:58:56,537 DEBUG subiquity.models.filesystem:1475 considering mount of 0:40 2023-11-03 18:58:56,537 DEBUG subiquity.models.filesystem:1475 considering mount of 0:34 2023-11-03 18:58:56,537 DEBUG subiquity.models.filesystem:1475 considering mount of 0:42 2023-11-03 18:58:56,537 DEBUG subiquity.models.filesystem:1475 considering mount of 0:43 2023-11-03 18:58:56,537 DEBUG subiquity.models.filesystem:1475 considering mount of 0:44 2023-11-03 18:58:56,537 DEBUG subiquity.models.filesystem:1475 considering mount of 0:64 2023-11-03 18:58:56,537 DEBUG subiquity.models.filesystem:1475 considering mount of 0:25 2023-11-03 18:58:56,537 DEBUG subiquity.models.filesystem:1475 considering mount of 0:46 2023-11-03 18:58:56,537 DEBUG subiquity.models.filesystem:1475 considering mount of 0:47 2023-11-03 18:58:56,537 DEBUG subiquity.models.filesystem:1475 considering mount of 7:4 2023-11-03 18:58:56,537 DEBUG subiquity.models.filesystem:1475 considering mount of 7:5 2023-11-03 18:58:56,537 DEBUG subiquity.models.filesystem:1475 considering mount of 7:3 2023-11-03 18:58:56,537 DEBUG subiquity.models.filesystem:1475 considering mount of 7:6 2023-11-03 18:58:56,537 DEBUG subiquity.models.filesystem:1475 considering mount of 7:11 2023-11-03 18:58:56,537 DEBUG subiquity.models.filesystem:1475 considering mount of 7:7 2023-11-03 18:58:56,537 DEBUG subiquity.models.filesystem:1475 considering mount of 7:8 2023-11-03 18:58:56,538 DEBUG subiquity.models.filesystem:1475 considering mount of 7:9 2023-11-03 18:58:56,538 DEBUG subiquity.models.filesystem:1475 considering mount of 7:10 2023-11-03 18:58:56,538 DEBUG subiquity.models.filesystem:1475 considering mount of 0:50 2023-11-03 18:58:56,538 DEBUG subiquity.models.filesystem:1475 considering mount of 0:72 2023-11-03 18:58:56,538 DEBUG subiquity.models.filesystem:1475 considering mount of 0:81 2023-11-03 18:58:56,538 DEBUG subiquity.models.filesystem:1475 considering mount of 0:4 2023-11-03 18:58:56,538 DEBUG subiquity.models.filesystem:1475 considering mount of 0:4 2023-11-03 18:58:56,538 DEBUG root:30 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False 2023-11-03 18:58:56,539 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/google-cloud-sdk: SUCCESS: 2023-11-03 18:58:56,540 DEBUG subiquity.server.controllers.filesystem:1297 block probing took 3.7 seconds 2023-11-03 18:58:56,540 DEBUG subiquity.server.controllers.filesystem:1474 start_monitor 2023-11-03 18:58:56,541 DEBUG root:30 finish: subiquity/Filesystem/_probe: SUCCESS: 2023-11-03 18:58:56,542 DEBUG root:30 start: subiquity/Locale/POST: 2023-11-03 18:58:56,542 DEBUG subiquity.server.controllers.locale:68 en_US.UTF-8 2023-11-03 18:58:56,543 DEBUG subiquity.models.subiquity:256 model locale for postinstall stage is configured, to go {'identity', 'network', 'codecs', 'drivers', 'timezone'} 2023-11-03 18:58:56,543 DEBUG root:30 finish: subiquity/Locale/POST: SUCCESS: 200 null 2023-11-03 18:58:56,543 DEBUG root:30 start: subiquity/SnapList/loader/fetch/slcli: 2023-11-03 18:58:56,544 DEBUG subiquitycore.utils:121 arun_command called: ['localectl', 'set-locale', 'en_US.UTF-8'] 2023-11-03 18:58:56,554 DEBUG root:30 start: subiquity/Keyboard/GET: 2023-11-03 18:58:56,582 DEBUG root:30 finish: subiquity/Keyboard/GET: SUCCESS: 200 {"setting": {"layout": "us", "variant": "", "toggle": null}, "layouts": [{"co... 2023-11-03 18:58:56,586 DEBUG root:30 start: subiquity/Keyboard/GET: 2023-11-03 18:58:56,611 DEBUG root:30 finish: subiquity/Keyboard/GET: SUCCESS: 200 {"setting": {"layout": "us", "variant": "", "toggle": null}, "layouts": [{"co... 2023-11-03 18:58:56,628 DEBUG root:30 start: subiquity/Keyboard/input_source_POST: 2023-11-03 18:58:56,629 DEBUG subiquitycore.utils:121 arun_command called: ['systemd-run', '--wait', '--uid=1000', '--setenv=DISPLAY=:0', '--setenv=XDG_RUNTIME_DIR=/run/user/1000', '--setenv=DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus', '--', 'gsettings', 'set', 'org.gnome.desktop.input-sources', 'sources', "[('xkb','us')]"] 2023-11-03 18:58:56,683 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=slcli HTTP/1.1" 200 None 2023-11-03 18:58:56,685 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/slcli: SUCCESS: 2023-11-03 18:58:56,685 DEBUG root:30 start: subiquity/SnapList/loader/fetch/doctl: 2023-11-03 18:58:56,719 DEBUG subiquitycore.utils:135 arun_command ['systemd-run', '--wait', '--uid=1000', '--setenv=DISPLAY=:0', '--setenv=XDG_RUNTIME_DIR=/run/user/1000', '--setenv=DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus', '--', 'gsettings', 'set', 'org.gnome.desktop.input-sources', 'sources', "[('xkb','us')]"] exited with code 0 2023-11-03 18:58:56,719 DEBUG root:30 finish: subiquity/Keyboard/input_source_POST: SUCCESS: 200 null 2023-11-03 18:58:56,857 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=doctl HTTP/1.1" 200 None 2023-11-03 18:58:56,859 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/doctl: SUCCESS: 2023-11-03 18:58:56,859 DEBUG root:30 start: subiquity/SnapList/loader/fetch/conjure-up: 2023-11-03 18:58:56,994 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=conjure-up HTTP/1.1" 200 None 2023-11-03 18:58:56,996 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/conjure-up: SUCCESS: 2023-11-03 18:58:56,996 DEBUG root:30 start: subiquity/SnapList/loader/fetch/postgresql10: 2023-11-03 18:58:57,156 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=postgresql10 HTTP/1.1" 200 1523 2023-11-03 18:58:57,157 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/postgresql10: SUCCESS: 2023-11-03 18:58:57,158 DEBUG root:30 start: subiquity/SnapList/loader/fetch/heroku: 2023-11-03 18:58:57,325 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=heroku HTTP/1.1" 200 None 2023-11-03 18:58:57,327 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/heroku: SUCCESS: 2023-11-03 18:58:57,327 DEBUG root:30 start: subiquity/SnapList/loader/fetch/keepalived: 2023-11-03 18:58:57,495 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=keepalived HTTP/1.1" 200 None 2023-11-03 18:58:57,497 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/keepalived: SUCCESS: 2023-11-03 18:58:57,497 DEBUG root:30 start: subiquity/SnapList/loader/fetch/prometheus: 2023-11-03 18:58:57,660 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=prometheus HTTP/1.1" 200 None 2023-11-03 18:58:57,662 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/prometheus: SUCCESS: 2023-11-03 18:58:57,662 DEBUG root:30 start: subiquity/SnapList/loader/fetch/juju: 2023-11-03 18:58:57,747 DEBUG subiquitycore.utils:135 arun_command ['localectl', 'set-locale', 'en_US.UTF-8'] exited with code 0 2023-11-03 18:58:57,892 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=juju HTTP/1.1" 200 None 2023-11-03 18:58:57,895 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/juju: SUCCESS: 2023-11-03 18:58:57,895 DEBUG root:30 finish: subiquity/SnapList/loader: SUCCESS: 2023-11-03 18:59:06,230 DEBUG root:30 start: subiquity/Keyboard/input_source_POST: 2023-11-03 18:59:06,230 DEBUG subiquitycore.utils:121 arun_command called: ['systemd-run', '--wait', '--uid=1000', '--setenv=DISPLAY=:0', '--setenv=XDG_RUNTIME_DIR=/run/user/1000', '--setenv=DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus', '--', 'gsettings', 'set', 'org.gnome.desktop.input-sources', 'sources', "[('xkb','is')]"] 2023-11-03 18:59:06,291 DEBUG subiquitycore.utils:135 arun_command ['systemd-run', '--wait', '--uid=1000', '--setenv=DISPLAY=:0', '--setenv=XDG_RUNTIME_DIR=/run/user/1000', '--setenv=DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus', '--', 'gsettings', 'set', 'org.gnome.desktop.input-sources', 'sources', "[('xkb','is')]"] exited with code 0 2023-11-03 18:59:06,292 DEBUG root:30 finish: subiquity/Keyboard/input_source_POST: SUCCESS: 200 null 2023-11-03 18:59:06,994 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 18:59:07,020 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 18:59:07,020 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 18:59:07,982 DEBUG root:30 start: subiquity/Keyboard/POST: 2023-11-03 18:59:07,983 DEBUG subiquity.server.controllers.keyboard:221 KeyboardSetting(layout='is', variant='', toggle=None) 2023-11-03 18:59:07,984 DEBUG subiquitycore.utils:121 arun_command called: ['setupcon', '--save', '--force', '--keyboard-only'] 2023-11-03 18:59:08,782 DEBUG subiquitycore.utils:135 arun_command ['setupcon', '--save', '--force', '--keyboard-only'] exited with code 0 2023-11-03 18:59:08,782 DEBUG subiquitycore.utils:121 arun_command called: ['/snap/ubuntu-budgie-installer/41/bin/subiquity/bin/subiquity-loadkeys'] 2023-11-03 18:59:08,786 DEBUG subiquitycore.utils:135 arun_command ['/snap/ubuntu-budgie-installer/41/bin/subiquity/bin/subiquity-loadkeys'] exited with code 0 2023-11-03 18:59:08,787 DEBUG subiquity.models.subiquity:256 model keyboard for install stage is configured, to go {'source', 'filesystem', 'network'} 2023-11-03 18:59:08,787 DEBUG root:30 finish: subiquity/Keyboard/POST: SUCCESS: 200 null 2023-11-03 18:59:09,888 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 18:59:09,908 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 18:59:09,909 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Vodafone-UwWV', 'no status')]} 2023-11-03 18:59:09,909 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 18:59:09,915 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 18:59:10,395 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 18:59:10,416 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 18:59:10,417 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 18:59:10,648 DEBUG root:30 start: subiquity/Source/GET: 2023-11-03 18:59:10,649 DEBUG root:30 finish: subiquity/Source/GET: SUCCESS: 200 {"sources": [{"name": "Ubuntu Budgie Desktop (minimized)", "description": "A ... 2023-11-03 18:59:10,650 DEBUG root:30 start: subiquity/Drivers/GET: 2023-11-03 18:59:10,651 DEBUG root:30 finish: subiquity/Drivers/GET: SUCCESS: 200 {"install": false, "drivers": null, "local_only": true, "search_drivers": false} 2023-11-03 18:59:10,652 DEBUG root:30 start: subiquity/Codecs/GET: 2023-11-03 18:59:10,652 DEBUG root:30 finish: subiquity/Codecs/GET: SUCCESS: 200 {"install": false} 2023-11-03 18:59:11,336 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 18:59:11,345 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 18:59:11,346 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Vodafone-UwWV', 'no status')]} 2023-11-03 18:59:11,346 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 18:59:11,368 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 18:59:11,895 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 18:59:11,916 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 18:59:11,917 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 18:59:12,509 DEBUG root:30 start: subiquity/Source/POST: 2023-11-03 18:59:12,510 DEBUG subiquity.models.subiquity:256 model source for install stage is configured, to go {'filesystem', 'network'} 2023-11-03 18:59:12,510 DEBUG subiquity.server.controllers.drivers:89 source variant has been set. Querying list of drivers. 2023-11-03 18:59:12,510 DEBUG root:30 finish: subiquity/Source/POST: SUCCESS: 200 null 2023-11-03 18:59:12,511 DEBUG subiquity.server.controllers.filesystem:414 got system None for variation default 2023-11-03 18:59:12,511 DEBUG root:30 start: subiquity/Drivers/_list_drivers: 2023-11-03 18:59:12,511 DEBUG root:30 start: subiquity/Drivers/_list_drivers/wait_apt: 2023-11-03 18:59:12,512 DEBUG root:30 start: subiquity/Drivers/POST: 2023-11-03 18:59:12,512 DEBUG subiquity.models.subiquity:256 model drivers for postinstall stage is configured, to go {'timezone', 'identity', 'codecs', 'network'} 2023-11-03 18:59:12,512 DEBUG root:30 finish: subiquity/Drivers/POST: SUCCESS: 200 null 2023-11-03 18:59:12,513 DEBUG root:30 start: subiquity/Codecs/POST: 2023-11-03 18:59:12,513 DEBUG subiquity.models.subiquity:256 model codecs for postinstall stage is configured, to go {'timezone', 'identity', 'network'} 2023-11-03 18:59:12,514 DEBUG root:30 finish: subiquity/Codecs/POST: SUCCESS: 200 null 2023-11-03 18:59:12,515 DEBUG root:30 start: subiquity/Filesystem/v2_GET: 2023-11-03 18:59:12,515 DEBUG subiquity.server.controllers.filesystem:996 suggested install minimum size: 9.636G 2023-11-03 18:59:12,524 DEBUG root:30 finish: subiquity/Filesystem/v2_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-sda", "label"... 2023-11-03 18:59:12,527 DEBUG root:30 start: subiquity/Filesystem/v2_GET: 2023-11-03 18:59:12,527 DEBUG subiquity.server.controllers.filesystem:996 suggested install minimum size: 9.636G 2023-11-03 18:59:12,533 DEBUG root:30 finish: subiquity/Filesystem/v2_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-sda", "label"... 2023-11-03 18:59:12,535 DEBUG root:30 start: subiquity/Filesystem/v2_guided_GET: 2023-11-03 18:59:12,536 DEBUG subiquity.server.controllers.filesystem:996 suggested install minimum size: 9.636G 2023-11-03 18:59:12,539 DEBUG root:30 finish: subiquity/Filesystem/v2_guided_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "configured": null, "targets": [{"di... 2023-11-03 18:59:12,541 DEBUG root:30 start: subiquity/Filesystem/has_bitlocker_GET: 2023-11-03 18:59:12,542 DEBUG root:30 finish: subiquity/Filesystem/has_bitlocker_GET: SUCCESS: 200 [] 2023-11-03 18:59:12,624 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 18:59:12,637 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 18:59:12,637 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Vodafone-UwWV', 'no status')]} 2023-11-03 18:59:12,638 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 18:59:12,644 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 18:59:19,737 DEBUG root:30 start: subiquity/Filesystem/has_bitlocker_GET: 2023-11-03 18:59:19,738 DEBUG root:30 finish: subiquity/Filesystem/has_bitlocker_GET: SUCCESS: 200 [] 2023-11-03 18:59:19,740 DEBUG root:30 start: subiquity/Filesystem/v2_GET: 2023-11-03 18:59:19,741 DEBUG subiquity.server.controllers.filesystem:996 suggested install minimum size: 9.636G 2023-11-03 18:59:19,748 DEBUG root:30 finish: subiquity/Filesystem/v2_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-sda", "label"... 2023-11-03 18:59:19,751 DEBUG root:30 start: subiquity/Filesystem/v2_guided_GET: 2023-11-03 18:59:19,752 DEBUG subiquity.server.controllers.filesystem:996 suggested install minimum size: 9.636G 2023-11-03 18:59:19,755 DEBUG root:30 finish: subiquity/Filesystem/v2_guided_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "configured": null, "targets": [{"di... 2023-11-03 18:59:19,759 DEBUG root:30 start: subiquity/Filesystem/v2_guided_POST: 2023-11-03 18:59:19,760 DEBUG subiquity.server.controllers.filesystem:1152 GuidedChoiceV2(target=GuidedStorageTargetReformat(disk_id='disk-sda', allowed=[, , , ], disallowed=[]), capability=, recovery_key=None, sizing_policy=, reset_partition=False) 2023-11-03 18:59:19,761 DEBUG subiquity.models.filesystem:2105 adding fat32 to Partition(device=disk-sda, size=1127219200, wipe='superblock', flag='boot', number=1, grub_device=True, offset=1048576, id='partition-0') 2023-11-03 18:59:19,761 DEBUG subiquity.models.filesystem:2105 adding ext4 to Partition(device=disk-sda, size=126906007552, wipe='superblock', number=2, grub_device=None, offset=1128267776, id='partition-1') 2023-11-03 18:59:19,762 DEBUG subiquity.server.controllers.filesystem:996 suggested install minimum size: 9.636G 2023-11-03 18:59:19,765 DEBUG root:30 finish: subiquity/Filesystem/v2_guided_POST: SUCCESS: 200 {"status": "DONE", "error_report": null, "configured": {"target": {"disk_id":... 2023-11-03 18:59:19,768 DEBUG root:30 start: subiquity/Filesystem/v2_POST: 2023-11-03 18:59:19,768 DEBUG subiquity.models.subiquity:256 model filesystem for install stage is configured, to go {'network'} 2023-11-03 18:59:19,768 DEBUG subiquity.server.controllers.filesystem:1487 stop_monitor 2023-11-03 18:59:19,768 DEBUG subiquity.server.controllers.filesystem:996 suggested install minimum size: 9.636G 2023-11-03 18:59:19,774 DEBUG root:30 finish: subiquity/Filesystem/v2_POST: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-sda", "label"... 2023-11-03 18:59:19,776 DEBUG root:30 start: subiquity/Filesystem/v2_GET: 2023-11-03 18:59:19,776 DEBUG subiquity.server.controllers.filesystem:996 suggested install minimum size: 9.636G 2023-11-03 18:59:19,782 DEBUG root:30 finish: subiquity/Filesystem/v2_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-sda", "label"... 2023-11-03 18:59:19,784 DEBUG root:30 start: subiquity/Filesystem/v2_orig_config_GET: 2023-11-03 18:59:19,785 DEBUG curtin:1325 Extracting storage config from probe data 2023-11-03 18:59:19,785 DEBUG curtin:73 /dev/sda is multipath device member? False 2023-11-03 18:59:19,785 DEBUG curtin:86 /dev/sda is multipath device partition? False 2023-11-03 18:59:19,785 DEBUG curtin:61 /dev/sda is multipath device? False 2023-11-03 18:59:19,785 DEBUG curtin:86 /dev/sda is multipath device partition? False 2023-11-03 18:59:19,785 DEBUG curtin:61 /dev/sda is multipath device? False 2023-11-03 18:59:19,811 DEBUG curtin:73 /dev/sda1 is multipath device member? False 2023-11-03 18:59:19,811 DEBUG curtin:86 /dev/sda1 is multipath device partition? False 2023-11-03 18:59:19,811 DEBUG curtin:61 /dev/sda1 is multipath device? False 2023-11-03 18:59:19,811 DEBUG curtin:86 /dev/sda1 is multipath device partition? False 2023-11-03 18:59:19,812 DEBUG curtin:86 /dev/sda1 is multipath device partition? False 2023-11-03 18:59:19,836 DEBUG curtin:73 /dev/sda2 is multipath device member? False 2023-11-03 18:59:19,836 DEBUG curtin:86 /dev/sda2 is multipath device partition? False 2023-11-03 18:59:19,836 DEBUG curtin:61 /dev/sda2 is multipath device? False 2023-11-03 18:59:19,836 DEBUG curtin:86 /dev/sda2 is multipath device partition? False 2023-11-03 18:59:19,837 DEBUG curtin:86 /dev/sda2 is multipath device partition? False 2023-11-03 18:59:19,862 DEBUG curtin:73 /dev/sdb is multipath device member? False 2023-11-03 18:59:19,862 DEBUG curtin:86 /dev/sdb is multipath device partition? False 2023-11-03 18:59:19,862 DEBUG curtin:61 /dev/sdb is multipath device? False 2023-11-03 18:59:19,862 DEBUG curtin:86 /dev/sdb is multipath device partition? False 2023-11-03 18:59:19,862 DEBUG curtin:61 /dev/sdb is multipath device? False 2023-11-03 18:59:19,887 DEBUG curtin:73 /dev/sdb1 is multipath device member? False 2023-11-03 18:59:19,887 DEBUG curtin:86 /dev/sdb1 is multipath device partition? False 2023-11-03 18:59:19,887 DEBUG curtin:61 /dev/sdb1 is multipath device? False 2023-11-03 18:59:19,887 DEBUG curtin:86 /dev/sdb1 is multipath device partition? False 2023-11-03 18:59:19,887 DEBUG curtin:86 /dev/sdb1 is multipath device partition? False 2023-11-03 18:59:19,913 DEBUG curtin:73 /dev/sdb2 is multipath device member? False 2023-11-03 18:59:19,913 DEBUG curtin:86 /dev/sdb2 is multipath device partition? False 2023-11-03 18:59:19,913 DEBUG curtin:61 /dev/sdb2 is multipath device? False 2023-11-03 18:59:19,913 DEBUG curtin:86 /dev/sdb2 is multipath device partition? False 2023-11-03 18:59:19,913 DEBUG curtin:86 /dev/sdb2 is multipath device partition? False 2023-11-03 18:59:19,938 DEBUG curtin:73 /dev/sdb3 is multipath device member? False 2023-11-03 18:59:19,938 DEBUG curtin:86 /dev/sdb3 is multipath device partition? False 2023-11-03 18:59:19,938 DEBUG curtin:61 /dev/sdb3 is multipath device? False 2023-11-03 18:59:19,938 DEBUG curtin:86 /dev/sdb3 is multipath device partition? False 2023-11-03 18:59:19,938 DEBUG curtin:86 /dev/sdb3 is multipath device partition? False 2023-11-03 18:59:19,963 DEBUG curtin:73 /dev/sdb4 is multipath device member? False 2023-11-03 18:59:19,964 DEBUG curtin:86 /dev/sdb4 is multipath device partition? False 2023-11-03 18:59:19,964 DEBUG curtin:61 /dev/sdb4 is multipath device? False 2023-11-03 18:59:19,964 DEBUG curtin:86 /dev/sdb4 is multipath device partition? False 2023-11-03 18:59:19,964 DEBUG curtin:86 /dev/sdb4 is multipath device partition? False 2023-11-03 18:59:19,989 DEBUG curtin:73 /dev/sda1 is multipath device member? False 2023-11-03 18:59:20,014 DEBUG curtin:73 /dev/sdb is multipath device member? False 2023-11-03 18:59:20,038 DEBUG curtin:73 /dev/sdb1 is multipath device member? False 2023-11-03 18:59:20,063 DEBUG curtin:73 /dev/sdb2 is multipath device member? False 2023-11-03 18:59:20,087 DEBUG curtin:73 /dev/sda2 is multipath device member? False 2023-11-03 18:59:20,112 DEBUG curtin:73 /dev/sdb4 is multipath device member? False 2023-11-03 18:59:20,161 DEBUG curtin:1332 Sorting extracted configurations 2023-11-03 18:59:20,161 INFO curtin:1351 Validating extracted storage config components 2023-11-03 18:59:20,206 DEBUG curtin:1368 Extracted (unmerged) storage config: storage: - id: disk-sda path: /dev/sda ptable: gpt serial: FORESEE_128GB_SSD_K44700J003729 type: disk wwn: '0x502b2a201d1c1b1a' - id: disk-sdb path: /dev/sdb ptable: gpt serial: Kingston_DataTraveler_3.0_40B0767E23131571065372E0-0:0 type: disk - device: disk-sda flag: boot id: partition-sda1 number: 1 offset: 1048576 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sda1 size: 1127219200 type: partition uuid: 908d9db1-e4e0-4a91-9730-a327cffdc1aa - device: disk-sda flag: linux id: partition-sda2 number: 2 offset: 1128267776 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sda2 size: 126906007552 type: partition uuid: 97307bf8-717e-4bdf-8089-fa1216a7ff2a - device: disk-sdb id: partition-sdb1 number: 1 offset: 32768 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb1 size: 3221395456 type: partition uuid: 23a89eda-eaed-4b01-bef3-0d031403b683 - device: disk-sdb flag: boot id: partition-sdb2 number: 2 offset: 3221428224 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sdb2 size: 5138432 type: partition uuid: 23a89eda-eaed-4b01-bef0-0d031403b683 - device: disk-sdb id: partition-sdb3 number: 3 offset: 3226566656 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb3 size: 307200 type: partition uuid: 23a89eda-eaed-4b01-bef1-0d031403b683 - device: disk-sdb flag: linux id: partition-sdb4 number: 4 offset: 3227516928 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sdb4 size: 27767341056 type: partition uuid: 950f9560-a726-484c-af82-45566c41cae8 - fstype: vfat id: format-partition-sda1 type: format volume: partition-sda1 - fstype: iso9660 id: format-disk-sdb type: format volume: disk-sdb - fstype: iso9660 id: format-partition-sdb1 type: format volume: partition-sdb1 - fstype: vfat id: format-partition-sdb2 type: format volume: partition-sdb2 - fstype: ext4 id: format-partition-sda2 type: format uuid: 91bf2730-b145-4993-8ae4-2838e60cf3e8 volume: partition-sda2 - fstype: ext4 id: format-partition-sdb4 type: format uuid: fa4eb7d9-9bab-4738-867e-856a933c64a6 volume: partition-sdb4 - device: format-partition-sdb1 id: mount-partition-sdb1 path: /cdrom type: mount 2023-11-03 18:59:20,207 DEBUG curtin:1372 Generating storage config dependencies 2023-11-03 18:59:20,207 DEBUG curtin:238 Validate: partition-sda1:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:59:20,207 DEBUG curtin:238 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:59:20,207 DEBUG curtin:238 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:59:20,207 DEBUG curtin:238 Validate: partition-sdb2:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:59:20,207 DEBUG curtin:238 Validate: partition-sdb3:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:59:20,208 DEBUG curtin:238 Validate: partition-sdb4:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:59:20,208 DEBUG curtin:238 Validate: format-partition-sda1:SourceType:format -> (DepId:partition-sda1 DepType:partition) in SourceDeps:{'disk', 'bcache', 'lvm_partition', 'raid', 'partition', 'dm_crypt'} ? result=True 2023-11-03 18:59:20,208 DEBUG curtin:238 Validate: partition-sda1:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:59:20,208 DEBUG curtin:238 Validate: format-disk-sdb:SourceType:format -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'bcache', 'lvm_partition', 'raid', 'partition', 'dm_crypt'} ? result=True 2023-11-03 18:59:20,208 DEBUG curtin:238 Validate: format-partition-sdb1:SourceType:format -> (DepId:partition-sdb1 DepType:partition) in SourceDeps:{'disk', 'bcache', 'lvm_partition', 'raid', 'partition', 'dm_crypt'} ? result=True 2023-11-03 18:59:20,208 DEBUG curtin:238 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:59:20,208 DEBUG curtin:238 Validate: format-partition-sdb2:SourceType:format -> (DepId:partition-sdb2 DepType:partition) in SourceDeps:{'disk', 'bcache', 'lvm_partition', 'raid', 'partition', 'dm_crypt'} ? result=True 2023-11-03 18:59:20,209 DEBUG curtin:238 Validate: partition-sdb2:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:59:20,209 DEBUG curtin:238 Validate: format-partition-sda2:SourceType:format -> (DepId:partition-sda2 DepType:partition) in SourceDeps:{'disk', 'bcache', 'lvm_partition', 'raid', 'partition', 'dm_crypt'} ? result=True 2023-11-03 18:59:20,209 DEBUG curtin:238 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:59:20,209 DEBUG curtin:238 Validate: format-partition-sdb4:SourceType:format -> (DepId:partition-sdb4 DepType:partition) in SourceDeps:{'disk', 'bcache', 'lvm_partition', 'raid', 'partition', 'dm_crypt'} ? result=True 2023-11-03 18:59:20,209 DEBUG curtin:238 Validate: partition-sdb4:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:59:20,209 DEBUG curtin:238 Validate: mount-partition-sdb1:SourceType:mount -> (DepId:format-partition-sdb1 DepType:format) in SourceDeps:{'format'} ? result=True 2023-11-03 18:59:20,209 DEBUG curtin:238 Validate: format-partition-sdb1:SourceType:format -> (DepId:partition-sdb1 DepType:partition) in SourceDeps:{'disk', 'bcache', 'lvm_partition', 'raid', 'partition', 'dm_crypt'} ? result=True 2023-11-03 18:59:20,209 DEBUG curtin:238 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'raid', 'partition', 'bcache'} ? result=True 2023-11-03 18:59:20,209 DEBUG curtin:1378 Merging storage config dependencies 2023-11-03 18:59:20,221 DEBUG curtin:1383 Merged storage config: storage: config: - id: disk-sda path: /dev/sda ptable: gpt serial: FORESEE_128GB_SSD_K44700J003729 type: disk wwn: '0x502b2a201d1c1b1a' - id: disk-sdb path: /dev/sdb ptable: gpt serial: Kingston_DataTraveler_3.0_40B0767E23131571065372E0-0:0 type: disk - fstype: iso9660 id: format-disk-sdb type: format volume: disk-sdb - device: disk-sda flag: boot id: partition-sda1 number: 1 offset: 1048576 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sda1 size: 1127219200 type: partition uuid: 908d9db1-e4e0-4a91-9730-a327cffdc1aa - device: disk-sda flag: linux id: partition-sda2 number: 2 offset: 1128267776 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sda2 size: 126906007552 type: partition uuid: 97307bf8-717e-4bdf-8089-fa1216a7ff2a - fstype: vfat id: format-partition-sda1 type: format volume: partition-sda1 - fstype: ext4 id: format-partition-sda2 type: format uuid: 91bf2730-b145-4993-8ae4-2838e60cf3e8 volume: partition-sda2 - device: disk-sdb id: partition-sdb1 number: 1 offset: 32768 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb1 size: 3221395456 type: partition uuid: 23a89eda-eaed-4b01-bef3-0d031403b683 - device: disk-sdb flag: boot id: partition-sdb2 number: 2 offset: 3221428224 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sdb2 size: 5138432 type: partition uuid: 23a89eda-eaed-4b01-bef0-0d031403b683 - device: disk-sdb id: partition-sdb3 number: 3 offset: 3226566656 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb3 size: 307200 type: partition uuid: 23a89eda-eaed-4b01-bef1-0d031403b683 - device: disk-sdb flag: linux id: partition-sdb4 number: 4 offset: 3227516928 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sdb4 size: 27767341056 type: partition uuid: 950f9560-a726-484c-af82-45566c41cae8 - fstype: iso9660 id: format-partition-sdb1 type: format volume: partition-sdb1 - fstype: vfat id: format-partition-sdb2 type: format volume: partition-sdb2 - fstype: ext4 id: format-partition-sdb4 type: format uuid: fa4eb7d9-9bab-4738-867e-856a933c64a6 volume: partition-sdb4 - device: format-partition-sdb1 id: mount-partition-sdb1 path: /cdrom type: mount version: 2 2023-11-03 18:59:20,221 DEBUG subiquity.models.filesystem:1463 majmin_to_dev {'8:0': Disk(ptable='gpt', serial='FORESEE_128GB_SSD_K44700J003729', wwn='0x502b2a201d1c1b1a', path='/dev/sda', preserve=True, id='disk-sda', type='disk'), '8:16': Disk(ptable='gpt', serial='Kingston_DataTraveler_3.0_40B0767E23131571065372E0-0:0', path='/dev/sdb', preserve=True, id='disk-sdb', type='disk'), '8:1': Partition(device=disk-sda, size=1127219200, flag='boot', number=1, preserve=True, offset=1048576, partition_type='c12a7328-f81f-11d2-ba4b-00a0c93ec93b', path='/dev/sda1', uuid='908d9db1-e4e0-4a91-9730-a327cffdc1aa', id='partition-sda1', type='partition'), '8:2': Partition(device=disk-sda, size=126906007552, flag='linux', number=2, preserve=True, offset=1128267776, partition_type='0fc63daf-8483-4772-8e79-3d69d8477de4', path='/dev/sda2', uuid='97307bf8-717e-4bdf-8089-fa1216a7ff2a', id='partition-sda2', type='partition'), '8:17': Partition(device=disk-sdb, size=3221395456, number=1, preserve=True, offset=32768, partition_type='ebd0a0a2-b9e5-4433-87c0-68b6b72699c7', path='/dev/sdb1', uuid='23a89eda-eaed-4b01-bef3-0d031403b683', id='partition-sdb1', type='partition'), '8:18': Partition(device=disk-sdb, size=5138432, flag='boot', number=2, preserve=True, offset=3221428224, partition_type='c12a7328-f81f-11d2-ba4b-00a0c93ec93b', path='/dev/sdb2', uuid='23a89eda-eaed-4b01-bef0-0d031403b683', id='partition-sdb2', type='partition'), '8:19': Partition(device=disk-sdb, size=307200, number=3, preserve=True, offset=3226566656, partition_type='ebd0a0a2-b9e5-4433-87c0-68b6b72699c7', path='/dev/sdb3', uuid='23a89eda-eaed-4b01-bef1-0d031403b683', id='partition-sdb3', type='partition'), '8:20': Partition(device=disk-sdb, size=27767341056, flag='linux', number=4, preserve=True, offset=3227516928, partition_type='0fc63daf-8483-4772-8e79-3d69d8477de4', path='/dev/sdb4', uuid='950f9560-a726-484c-af82-45566c41cae8', id='partition-sdb4', type='partition')} 2023-11-03 18:59:20,222 DEBUG subiquity.models.filesystem:1475 considering mount of 0:28 2023-11-03 18:59:20,222 DEBUG subiquity.models.filesystem:1475 considering mount of 0:22 2023-11-03 18:59:20,222 DEBUG subiquity.models.filesystem:1475 considering mount of 0:23 2023-11-03 18:59:20,222 DEBUG subiquity.models.filesystem:1475 considering mount of 0:5 2023-11-03 18:59:20,222 DEBUG subiquity.models.filesystem:1475 considering mount of 0:25 2023-11-03 18:59:20,222 DEBUG subiquity.models.filesystem:1475 considering mount of 8:17 2023-11-03 18:59:20,222 DEBUG subiquity.models.filesystem:1480 /dev/sdb1 is mounted 2023-11-03 18:59:20,222 DEBUG subiquity.models.filesystem:1475 considering mount of 7:0 2023-11-03 18:59:20,222 DEBUG subiquity.models.filesystem:1475 considering mount of 8:20 2023-11-03 18:59:20,222 DEBUG subiquity.models.filesystem:1480 /dev/sdb4 is mounted 2023-11-03 18:59:20,222 DEBUG subiquity.models.filesystem:1475 considering mount of 8:20 2023-11-03 18:59:20,222 DEBUG subiquity.models.filesystem:1480 /dev/sdb4 is mounted 2023-11-03 18:59:20,222 DEBUG subiquity.models.filesystem:1475 considering mount of 0:28 2023-11-03 18:59:20,222 DEBUG subiquity.models.filesystem:1475 considering mount of 0:45 2023-11-03 18:59:20,222 DEBUG subiquity.models.filesystem:1475 considering mount of 7:4 2023-11-03 18:59:20,223 DEBUG subiquity.models.filesystem:1475 considering mount of 7:5 2023-11-03 18:59:20,223 DEBUG subiquity.models.filesystem:1475 considering mount of 7:3 2023-11-03 18:59:20,223 DEBUG subiquity.models.filesystem:1475 considering mount of 7:6 2023-11-03 18:59:20,223 DEBUG subiquity.models.filesystem:1475 considering mount of 7:11 2023-11-03 18:59:20,223 DEBUG subiquity.models.filesystem:1475 considering mount of 7:7 2023-11-03 18:59:20,223 DEBUG subiquity.models.filesystem:1475 considering mount of 7:8 2023-11-03 18:59:20,223 DEBUG subiquity.models.filesystem:1475 considering mount of 7:9 2023-11-03 18:59:20,223 DEBUG subiquity.models.filesystem:1475 considering mount of 7:10 2023-11-03 18:59:20,223 DEBUG subiquity.models.filesystem:1475 considering mount of 0:28 2023-11-03 18:59:20,223 DEBUG subiquity.models.filesystem:1475 considering mount of 0:6 2023-11-03 18:59:20,223 DEBUG subiquity.models.filesystem:1475 considering mount of 0:35 2023-11-03 18:59:20,223 DEBUG subiquity.models.filesystem:1475 considering mount of 0:36 2023-11-03 18:59:20,223 DEBUG subiquity.models.filesystem:1475 considering mount of 0:37 2023-11-03 18:59:20,223 DEBUG subiquity.models.filesystem:1475 considering mount of 0:38 2023-11-03 18:59:20,223 DEBUG subiquity.models.filesystem:1475 considering mount of 0:12 2023-11-03 18:59:20,223 DEBUG subiquity.models.filesystem:1475 considering mount of 0:7 2023-11-03 18:59:20,223 DEBUG subiquity.models.filesystem:1475 considering mount of 0:41 2023-11-03 18:59:20,223 DEBUG subiquity.models.filesystem:1475 considering mount of 0:21 2023-11-03 18:59:20,224 DEBUG subiquity.models.filesystem:1475 considering mount of 0:39 2023-11-03 18:59:20,224 DEBUG subiquity.models.filesystem:1475 considering mount of 0:24 2023-11-03 18:59:20,224 DEBUG subiquity.models.filesystem:1475 considering mount of 0:33 2023-11-03 18:59:20,224 DEBUG subiquity.models.filesystem:1475 considering mount of 0:20 2023-11-03 18:59:20,224 DEBUG subiquity.models.filesystem:1475 considering mount of 0:40 2023-11-03 18:59:20,224 DEBUG subiquity.models.filesystem:1475 considering mount of 0:34 2023-11-03 18:59:20,224 DEBUG subiquity.models.filesystem:1475 considering mount of 0:42 2023-11-03 18:59:20,224 DEBUG subiquity.models.filesystem:1475 considering mount of 0:43 2023-11-03 18:59:20,224 DEBUG subiquity.models.filesystem:1475 considering mount of 0:44 2023-11-03 18:59:20,224 DEBUG subiquity.models.filesystem:1475 considering mount of 0:64 2023-11-03 18:59:20,224 DEBUG subiquity.models.filesystem:1475 considering mount of 0:25 2023-11-03 18:59:20,224 DEBUG subiquity.models.filesystem:1475 considering mount of 0:46 2023-11-03 18:59:20,224 DEBUG subiquity.models.filesystem:1475 considering mount of 0:47 2023-11-03 18:59:20,224 DEBUG subiquity.models.filesystem:1475 considering mount of 7:4 2023-11-03 18:59:20,224 DEBUG subiquity.models.filesystem:1475 considering mount of 7:5 2023-11-03 18:59:20,224 DEBUG subiquity.models.filesystem:1475 considering mount of 7:3 2023-11-03 18:59:20,225 DEBUG subiquity.models.filesystem:1475 considering mount of 7:6 2023-11-03 18:59:20,225 DEBUG subiquity.models.filesystem:1475 considering mount of 7:11 2023-11-03 18:59:20,225 DEBUG subiquity.models.filesystem:1475 considering mount of 7:7 2023-11-03 18:59:20,225 DEBUG subiquity.models.filesystem:1475 considering mount of 7:8 2023-11-03 18:59:20,225 DEBUG subiquity.models.filesystem:1475 considering mount of 7:9 2023-11-03 18:59:20,225 DEBUG subiquity.models.filesystem:1475 considering mount of 7:10 2023-11-03 18:59:20,225 DEBUG subiquity.models.filesystem:1475 considering mount of 0:50 2023-11-03 18:59:20,225 DEBUG subiquity.models.filesystem:1475 considering mount of 0:72 2023-11-03 18:59:20,225 DEBUG subiquity.models.filesystem:1475 considering mount of 0:81 2023-11-03 18:59:20,225 DEBUG subiquity.models.filesystem:1475 considering mount of 0:4 2023-11-03 18:59:20,225 DEBUG subiquity.models.filesystem:1475 considering mount of 0:4 2023-11-03 18:59:20,225 DEBUG subiquity.server.controllers.filesystem:996 suggested install minimum size: 9.636G 2023-11-03 18:59:20,230 DEBUG root:30 finish: subiquity/Filesystem/v2_orig_config_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-sda", "label"... 2023-11-03 18:59:21,807 DEBUG root:30 start: subiquity/TimeZone/GET: 2023-11-03 18:59:21,808 DEBUG root:30 finish: subiquity/TimeZone/GET: SUCCESS: 200 {"timezone": "Atlantic/Reykjavik", "from_geoip": true} 2023-11-03 18:59:22,008 INFO root:30 start: subiquity/Meta/mark_configured_POST: 2023-11-03 18:59:22,009 DEBUG subiquitycore.models.network:467 has_network True 2023-11-03 18:59:22,009 DEBUG subiquity.models.subiquity:256 model network for install stage is configured, to go set() 2023-11-03 18:59:22,009 DEBUG subiquity.models.subiquity:256 model network for postinstall stage is configured, to go {'identity', 'timezone'} 2023-11-03 18:59:22,010 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null 2023-11-03 18:59:22,012 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "NEEDS_CONFIRMATION", "confirming_tty": "", "error": null, "cloud_i... 2023-11-03 18:59:22,013 INFO root:30 start: subiquity/Meta/confirm_POST: 2023-11-03 18:59:22,014 DEBUG subiquity.models.subiquity:256 model ssh for postinstall stage is configured, to go {'identity', 'timezone'} 2023-11-03 18:59:22,015 DEBUG subiquity.models.subiquity:256 model snaplist for postinstall stage is configured, to go {'identity', 'timezone'} 2023-11-03 18:59:22,015 INFO root:30 finish: subiquity/Meta/confirm_POST: SUCCESS: 200 null 2023-11-03 18:59:22,016 DEBUG root:30 finish: subiquity/OEM/load_metapackages_list/wait_confirmation: SUCCESS: 2023-11-03 18:59:22,016 DEBUG root:30 start: subiquity/OEM/load_metapackages_list/wait_apt: 2023-11-03 18:59:22,017 INFO root:30 start: subiquity/Install/install/configure_apt: configuring apt 2023-11-03 18:59:22,018 DEBUG root:30 start: subiquity/Mirror/waiting: 2023-11-03 18:59:22,019 DEBUG root:30 finish: subiquity/Mirror/waiting: SUCCESS: 2023-11-03 18:59:22,019 DEBUG subiquity.server.controllers.mirror:202 Iterating over country-mirror 2023-11-03 18:59:22,022 DEBUG curtin:117 Setting up Layered Source for stack ['///cdrom/casper/minimal.squashfs', '///cdrom/casper/minimal.standard.squashfs', '///cdrom/casper/minimal.standard.no-languages.squashfs'] 2023-11-03 18:59:22,022 DEBUG curtin:95 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.squashfs', '/tmp/tmpogrnfbri/minimal.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-11-03 18:59:22,046 DEBUG curtin:95 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.standard.squashfs', '/tmp/tmpogrnfbri/minimal.standard.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-11-03 18:59:22,066 DEBUG curtin:95 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.standard.no-languages.squashfs', '/tmp/tmpogrnfbri/minimal.standard.no-languages.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-11-03 18:59:22,075 DEBUG curtin:95 Running command ['mount', '-o', 'lowerdir=/tmp/tmpogrnfbri/minimal.standard.no-languages.squashfs.dir:/tmp/tmpogrnfbri/minimal.standard.squashfs.dir:/tmp/tmpogrnfbri/minimal.squashfs.dir', '-t', 'overlay', 'overlay', '/tmp/tmpogrnfbri/root.dir'] with allowed return codes [0] (capture=True) 2023-11-03 18:59:22,081 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', 'mount', '-o', 'lowerdir=/tmp/tmpogrnfbri/root.dir,upperdir=/tmp/tmpj0vbwr9k/upper,workdir=/tmp/tmpj0vbwr9k/work', '-t', 'overlay', 'overlay', '/tmp/tmpj0vbwr9k/mount'] 2023-11-03 18:59:22,091 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-03 18:59:22,092 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini... 2023-11-03 18:59:22,093 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-03 18:59:22,170 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3402.1"}}', '-c', '/var/log/installer/subiquity-curtin-apt.conf', 'apt-config', '-t', '/tmp/tmpj0vbwr9k/mount'] 2023-11-03 18:59:23,244 DEBUG root:30 start: subiquity/TimeZone/POST: 2023-11-03 18:59:23,456 DEBUG subiquity.models.subiquity:256 model timezone for postinstall stage is configured, to go {'identity'} 2023-11-03 18:59:23,457 DEBUG root:30 finish: subiquity/TimeZone/POST: SUCCESS: 200 null 2023-11-03 18:59:23,458 DEBUG root:30 start: subiquity/Identity/GET: 2023-11-03 18:59:23,458 DEBUG root:30 finish: subiquity/Identity/GET: SUCCESS: 200 {"realname": "", "username": "", "crypted_password": "", "hostname": ""} 2023-11-03 18:59:23,466 DEBUG root:30 start: subiquity/Ad/has_support_GET: 2023-11-03 18:59:23,466 DEBUG root:30 finish: subiquity/Ad/has_support_GET: SUCCESS: 200 false 2023-11-03 18:59:24,455 DEBUG subiquitycore.utils:154 astart_command called: ['apt-get', 'update', '-oAPT::Update::Error-Mode=any', '-oAPT::Sandbox::User=root', '-oAcquire::IndexTargets::deb::Packages::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::Translations::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::DEP-11::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::DEP-11-icons-small::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::DEP-11-icons::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::DEP-11-icons-hidpi::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::DEP-11-icons-large::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::DEP-11-icons-large-hidpi::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::CNF::DefaultEnabled=false', '-oAcquire::IndexTargets::deb-src::Sources::DefaultEnabled=false'] 2023-11-03 18:59:26,073 DEBUG subiquity.server.controllers.mirror:176 Mirror checking successful 2023-11-03 18:59:26,073 DEBUG subiquity.server.controllers.mirror:178 APT output follows 2023-11-03 18:59:26,073 DEBUG subiquity.server.controllers.mirror:180 Get:1 https://is.archive.ubuntu.com/ubuntu noble InRelease [213 kB] 2023-11-03 18:59:26,073 DEBUG subiquity.server.controllers.mirror:180 Hit:2 https://is.archive.ubuntu.com/ubuntu noble-updates InRelease 2023-11-03 18:59:26,073 DEBUG subiquity.server.controllers.mirror:180 Get:3 https://is.archive.ubuntu.com/ubuntu noble-backports InRelease [74.9 kB] 2023-11-03 18:59:26,073 DEBUG subiquity.server.controllers.mirror:180 Fetched 288 kB in 1s (425 kB/s) 2023-11-03 18:59:26,073 DEBUG subiquity.server.controllers.mirror:180 Reading package lists... 2023-11-03 18:59:26,073 DEBUG curtin:117 Setting up Layered Source for stack ['///cdrom/casper/minimal.squashfs', '///cdrom/casper/minimal.standard.squashfs', '///cdrom/casper/minimal.standard.no-languages.squashfs'] 2023-11-03 18:59:26,074 DEBUG curtin:95 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.squashfs', '/tmp/tmpjywx0at6/minimal.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-11-03 18:59:26,096 DEBUG curtin:95 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.standard.squashfs', '/tmp/tmpjywx0at6/minimal.standard.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-11-03 18:59:26,113 DEBUG curtin:95 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.standard.no-languages.squashfs', '/tmp/tmpjywx0at6/minimal.standard.no-languages.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-11-03 18:59:26,127 DEBUG curtin:95 Running command ['mount', '-o', 'lowerdir=/tmp/tmpjywx0at6/minimal.standard.no-languages.squashfs.dir:/tmp/tmpjywx0at6/minimal.standard.squashfs.dir:/tmp/tmpjywx0at6/minimal.squashfs.dir', '-t', 'overlay', 'overlay', '/tmp/tmpjywx0at6/root.dir'] with allowed return codes [0] (capture=True) 2023-11-03 18:59:26,140 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', 'mount', '-o', 'lowerdir=/tmp/tmpjywx0at6/root.dir,upperdir=/tmp/tmp79apy42s/upper,workdir=/tmp/tmp79apy42s/work', '-t', 'overlay', 'overlay', '/tmp/tmp79apy42s/mount'] 2023-11-03 18:59:26,200 DEBUG root:30 start: subiquity/Identity/validate_username_GET: 2023-11-03 18:59:26,201 DEBUG root:30 finish: subiquity/Identity/validate_username_GET: SUCCESS: 200 "OK" 2023-11-03 18:59:26,237 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3402.2"}}', '-c', '/var/log/installer/subiquity-curtin-apt.conf', 'apt-config', '-t', '/tmp/tmp79apy42s/mount'] 2023-11-03 18:59:26,838 DEBUG root:30 start: subiquity/Identity/validate_username_GET: 2023-11-03 18:59:26,839 DEBUG root:30 finish: subiquity/Identity/validate_username_GET: SUCCESS: 200 "OK" 2023-11-03 18:59:28,388 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', 'mount', '-o', 'lowerdir=/tmp/tmp79apy42s/upper:/tmp/tmpjywx0at6/root.dir,upperdir=/tmp/tmpp39vf2xt/upper,workdir=/tmp/tmpp39vf2xt/work', '-t', 'overlay', 'overlay', '/tmp/tmpp39vf2xt/mount'] 2023-11-03 18:59:28,447 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', 'mount', '-o', 'bind', '/cdrom', '/tmp/tmpp39vf2xt/mount/cdrom'] 2023-11-03 18:59:28,489 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3402.3"}}', 'in-target', '-t', '/tmp/tmpp39vf2xt/mount', '--', 'apt-get', 'update'] 2023-11-03 18:59:36,986 INFO root:30 finish: subiquity/Install/install/configure_apt: SUCCESS: configuring apt 2023-11-03 18:59:36,987 INFO root:30 start: subiquity/Install/install/curtin_install: installing system 2023-11-03 18:59:36,988 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step: executing curtin install initial step 2023-11-03 18:59:36,994 DEBUG root:30 finish: subiquity/Drivers/_list_drivers/wait_apt: SUCCESS: 2023-11-03 18:59:36,994 DEBUG root:30 finish: subiquity/Drivers/_list_drivers: SUCCESS: 2023-11-03 18:59:36,994 DEBUG root:30 finish: subiquity/OEM/load_metapackages_list/wait_apt: SUCCESS: 2023-11-03 18:59:36,995 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', 'mount', '-o', 'lowerdir=/tmp/tmpp39vf2xt/upper:/tmp/tmp79apy42s/upper:/tmp/tmpjywx0at6/root.dir,upperdir=/tmp/tmpeos6jsl9/upper,workdir=/tmp/tmpeos6jsl9/work', '-t', 'overlay', 'overlay', '/tmp/tmpeos6jsl9/mount'] 2023-11-03 18:59:36,996 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3402.4"}}', '-c', '/var/log/installer/curtin-install/subiquity-initial.conf', 'install', '--set', 'json:stages=[]'] 2023-11-03 18:59:37,077 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', 'chroot', '/tmp/tmpeos6jsl9/mount', 'sh', '-c', 'command -v ubuntu-drivers'] 2023-11-03 18:59:37,134 INFO root:30 start: subiquity/Meta/mark_configured_POST: 2023-11-03 18:59:37,134 DEBUG subiquity.models.subiquity:256 model active_directory for postinstall stage is configured, to go {'identity'} 2023-11-03 18:59:37,135 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null 2023-11-03 18:59:37,146 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--property', 'PrivateMounts=yes', '--pipe', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3402.5"}}', 'in-target', '-t', '/tmp/tmpeos6jsl9/mount', '--', 'ubuntu-drivers', 'list-oem'] 2023-11-03 18:59:37,212 DEBUG root:30 start: subiquity/Identity/POST: 2023-11-03 18:59:37,213 DEBUG subiquity.models.subiquity:256 model identity for postinstall stage is configured, to go set() 2023-11-03 18:59:37,213 DEBUG root:30 finish: subiquity/Identity/POST: SUCCESS: 200 null 2023-11-03 18:59:37,222 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-03 18:59:37,222 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini... 2023-11-03 18:59:37,823 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-03 18:59:37,824 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini... 2023-11-03 18:59:37,872 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-03 18:59:38,883 DEBUG root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step: SUCCESS: executing curtin install initial step 2023-11-03 18:59:38,884 DEBUG subiquity.models.filesystem:1838 mountpoints {'/boot/efi': 'mount-0', '/': 'mount-1'} 2023-11-03 18:59:38,884 DEBUG subiquity.models.filesystem:1828 cannot emit action to mount /boot/efi until that for / is emitted 2023-11-03 18:59:38,885 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step: executing curtin install partitioning step 2023-11-03 18:59:38,895 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3402.6"}}', '-c', '/var/log/installer/curtin-install/subiquity-partitioning.conf', 'install', '--set', 'json:stages=["partitioning"]', 'cp:///tmp/tmpp39vf2xt/mount'] 2023-11-03 18:59:39,000 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 18:59:39,011 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 18:59:39,011 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 18:59:39,832 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 18:59:39,856 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 18:59:39,857 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Vodafone-UwWV', 'no status')]} 2023-11-03 18:59:39,857 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 18:59:39,880 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 18:59:42,598 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', 'umount', '/tmp/tmpeos6jsl9/mount'] 2023-11-03 18:59:42,662 DEBUG subiquity.server.controllers.oem:192 OEM meta-packages to install: [] 2023-11-03 18:59:42,662 DEBUG root:30 finish: subiquity/OEM/load_metapackages_list: SUCCESS: 2023-11-03 18:59:52,500 DEBUG subiquity.server.controllers.filesystem:504 recording path '/dev/sda' for device disk-sda 2023-11-03 18:59:52,500 DEBUG subiquity.server.controllers.filesystem:504 recording path '/dev/sda1' for device partition-0 2023-11-03 18:59:52,500 DEBUG subiquity.server.controllers.filesystem:504 recording path '/dev/sda2' for device partition-1 2023-11-03 18:59:52,501 DEBUG root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step: SUCCESS: executing curtin install partitioning step 2023-11-03 18:59:52,501 DEBUG subiquity.models.subiquity:532 merging config from 2023-11-03 18:59:52,502 DEBUG subiquity.models.filesystem:1838 mountpoints {'/boot/efi': 'mount-0', '/': 'mount-1'} 2023-11-03 18:59:52,502 DEBUG subiquity.models.filesystem:1828 cannot emit action to mount /boot/efi until that for / is emitted 2023-11-03 18:59:52,502 DEBUG subiquity.models.subiquity:532 merging config from 2023-11-03 18:59:52,503 DEBUG subiquity.models.subiquity:532 merging config from 2023-11-03 18:59:52,503 DEBUG subiquity.models.subiquity:532 merging config from 2023-11-03 18:59:52,504 DEBUG subiquity.models.subiquity:532 merging config from 2023-11-03 18:59:52,504 DEBUG subiquity.models.subiquity:532 merging config from 2023-11-03 18:59:52,505 DEBUG subiquity.cloudinit:19 Failed to load combined-cloud-config, file not found. This is expected for cloud-init <= v23.2.1. 2023-11-03 18:59:52,506 DEBUG subiquity.models.subiquity:532 merging config from 2023-11-03 18:59:52,507 DEBUG subiquity.models.subiquity:532 merging config from 2023-11-03 18:59:52,508 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step: executing curtin install extract step 2023-11-03 18:59:52,521 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3402.7"}}', '-c', '/var/log/installer/curtin-install/subiquity-extract.conf', 'install', '--set', 'json:stages=["extract"]', 'cp:///tmp/tmpp39vf2xt/mount'] 2023-11-03 19:00:22,023 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:00:22,080 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:00:22,080 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:00:22,740 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:00:22,764 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:00:22,765 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Vodafone-UwWV', 'no status')]} 2023-11-03 19:00:22,765 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:00:22,784 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:01:26,038 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:01:26,072 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:01:26,072 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:01:28,948 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:01:28,956 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:01:28,956 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'Hringdu-7WWf', 'no status')]} 2023-11-03 19:01:28,956 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:01:28,976 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:03:00,983 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:03:01,033 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:03:01,033 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:03:01,944 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:03:01,956 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:03:01,957 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Vodafone-UwWV', 'no status')]} 2023-11-03 19:03:01,957 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:03:01,977 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:03:02,484 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:03:02,493 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:03:02,493 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:03:03,216 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:03:03,236 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:03:03,236 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Vodafone-UwWV', 'no status')]} 2023-11-03 19:03:03,236 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:03:03,246 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:03:03,986 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:03:04,008 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:03:04,008 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:03:04,712 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:03:04,741 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:03:04,741 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Vodafone-UwWV', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status')]} 2023-11-03 19:03:04,741 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:03:04,754 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:03:05,497 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:03:05,531 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:03:05,531 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:03:06,212 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:03:06,229 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:03:06,230 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Vodafone-UwWV', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'NOVA-K6LAQ9', 'no status')]} 2023-11-03 19:03:06,230 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:03:06,243 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:03:06,989 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:03:07,004 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:03:07,005 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:03:09,900 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:03:09,916 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:03:09,916 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Vodafone-UwWV', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'NOVA-K6LAQ9', 'no status')]} 2023-11-03 19:03:09,917 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:03:09,926 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:03:44,571 DEBUG root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step: SUCCESS: executing curtin install extract step 2023-11-03 19:03:44,572 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', 'mount', '-o', 'bind', '/cdrom', '/target/cdrom'] 2023-11-03 19:03:44,681 DEBUG root:30 start: subiquity/Install/install/curtin_install/setup_target: configuring keyboard 2023-11-03 19:03:44,682 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3402.8"}}', 'in-target', '-t', '/target', '--', 'setupcon', '--save-only'] 2023-11-03 19:03:47,937 DEBUG root:30 finish: subiquity/Install/install/curtin_install/setup_target: SUCCESS: configuring keyboard 2023-11-03 19:03:47,938 DEBUG subiquitycore.utils:121 arun_command called: ['grep-status', '--whole-pkg', '-FProvides', 'linux-image', '--and', '-FStatus', 'installed', '--show-field=Package', '--no-field-names', '/target/var/lib/dpkg/status'] 2023-11-03 19:03:47,966 DEBUG subiquitycore.utils:135 arun_command ['grep-status', '--whole-pkg', '-FProvides', 'linux-image', '--and', '-FStatus', 'installed', '--show-field=Package', '--no-field-names', '/target/var/lib/dpkg/status'] exited with code 1 2023-11-03 19:03:47,968 DEBUG subiquity.models.subiquity:532 merging config from 2023-11-03 19:03:47,968 DEBUG subiquity.models.filesystem:1838 mountpoints {'/boot/efi': 'mount-0', '/': 'mount-1'} 2023-11-03 19:03:47,969 DEBUG subiquity.models.filesystem:1828 cannot emit action to mount /boot/efi until that for / is emitted 2023-11-03 19:03:47,969 DEBUG subiquity.models.subiquity:532 merging config from 2023-11-03 19:03:47,969 DEBUG subiquity.models.subiquity:532 merging config from 2023-11-03 19:03:47,970 DEBUG subiquity.models.subiquity:532 merging config from 2023-11-03 19:03:47,970 DEBUG subiquity.models.subiquity:532 merging config from 2023-11-03 19:03:47,970 DEBUG subiquity.models.subiquity:532 merging config from 2023-11-03 19:03:47,970 DEBUG subiquity.cloudinit:19 Failed to load combined-cloud-config, file not found. This is expected for cloud-init <= v23.2.1. 2023-11-03 19:03:47,971 DEBUG subiquity.models.subiquity:532 merging config from 2023-11-03 19:03:47,971 DEBUG subiquity.models.subiquity:532 merging config from 2023-11-03 19:03:47,972 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step: executing curtin install curthooks step 2023-11-03 19:03:47,986 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3402.9"}}', '-c', '/var/log/installer/curtin-install/subiquity-curthooks.conf', 'install', '--set', 'json:stages=["curthooks"]'] 2023-11-03 19:05:01,042 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:05:01,064 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:05:01,064 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:05:01,984 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:05:02,004 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:05:02,005 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Vodafone-UwWV', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'NOVA-K6LAQ9', 'no status')]} 2023-11-03 19:05:02,005 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:05:02,013 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:05:13,839 DEBUG root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step: SUCCESS: executing curtin install curthooks step 2023-11-03 19:05:13,840 INFO root:30 finish: subiquity/Install/install/curtin_install: SUCCESS: installing system 2023-11-03 19:05:13,843 INFO root:30 start: subiquity/Install/install/postinstall: final system configuration 2023-11-03 19:05:13,844 DEBUG subiquity.models.filesystem:1838 mountpoints {'/boot/efi': 'mount-0', '/': 'mount-1'} 2023-11-03 19:05:13,845 DEBUG subiquity.models.filesystem:1828 cannot emit action to mount /boot/efi until that for / is emitted 2023-11-03 19:05:13,858 DEBUG root:30 start: subiquity/Install/install/postinstall/get_target_packages: calculating extra packages to install 2023-11-03 19:05:13,859 DEBUG subiquitycore.utils:121 arun_command called: ['chroot', '/target', 'check-language-support', '-l', 'en_US'] 2023-11-03 19:05:13,862 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini... 2023-11-03 19:05:13,863 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini... 2023-11-03 19:05:13,912 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-03 19:05:13,913 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-03 19:05:16,122 DEBUG subiquitycore.utils:135 arun_command ['chroot', '/target', 'check-language-support', '-l', 'en_US'] exited with code 0 2023-11-03 19:05:16,123 DEBUG root:30 finish: subiquity/Install/install/postinstall/get_target_packages: SUCCESS: calculating extra packages to install 2023-11-03 19:05:16,123 DEBUG root:30 start: subiquity/Install/install/postinstall/install_wpasupplicant: installing wpasupplicant 2023-11-03 19:05:16,123 DEBUG root:30 start: subiquity/Install/install/postinstall/install_wpasupplicant/retrieving: retrieving wpasupplicant 2023-11-03 19:05:16,124 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3402.10"}}', 'system-install', '-t', '/target', '--download-only', '--', 'wpasupplicant'] 2023-11-03 19:05:22,585 DEBUG root:30 finish: subiquity/Install/install/postinstall/install_wpasupplicant/retrieving: SUCCESS: retrieving wpasupplicant 2023-11-03 19:05:22,586 DEBUG root:30 start: subiquity/Install/install/postinstall/install_wpasupplicant/unpacking: unpacking wpasupplicant 2023-11-03 19:05:22,587 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3402.11"}}', 'system-install', '-t', '/target', '--assume-downloaded', '--', 'wpasupplicant'] 2023-11-03 19:05:26,113 DEBUG root:30 finish: subiquity/Install/install/postinstall/install_wpasupplicant/unpacking: SUCCESS: unpacking wpasupplicant 2023-11-03 19:05:26,113 DEBUG root:30 finish: subiquity/Install/install/postinstall/install_wpasupplicant: SUCCESS: installing wpasupplicant 2023-11-03 19:05:26,113 DEBUG root:30 start: subiquity/Install/install/postinstall/configure_cloud_init: configuring cloud-init 2023-11-03 19:05:26,115 DEBUG subiquitycore.utils:79 run_command called: ['chroot', '/target', 'getent', 'group'] 2023-11-03 19:05:26,122 DEBUG subiquitycore.utils:98 run_command ['chroot', '/target', 'getent', 'group'] exited with code 0 2023-11-03 19:05:26,126 DEBUG cloudinit.util:1579 Reading from /snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False) 2023-11-03 19:05:26,139 DEBUG cloudinit.util:1590 Read 136743 bytes from /snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json 2023-11-03 19:05:26,149 DEBUG subiquity.cloudinit:19 Failed to load combined-cloud-config, file not found. This is expected for cloud-init <= v23.2.1. 2023-11-03 19:05:26,154 DEBUG root:30 finish: subiquity/Install/install/postinstall/configure_cloud_init: SUCCESS: configuring cloud-init 2023-11-03 19:05:26,155 DEBUG root:30 start: subiquity/Install/install/postinstall/run_unattended_upgrades: downloading and installing security updates 2023-11-03 19:05:26,157 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "UU_RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_... 2023-11-03 19:05:26,157 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "UU_RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_... 2023-11-03 19:05:26,158 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3402.12"}}', 'in-target', '-t', '/target', '--', 'unattended-upgrades', '-v'] 2023-11-03 19:05:26,318 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-03 19:05:26,319 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-03 19:05:28,349 DEBUG root:30 finish: subiquity/Install/install/postinstall/run_unattended_upgrades: SUCCESS: downloading and installing security updates 2023-11-03 19:05:28,349 DEBUG root:30 start: subiquity/Install/install/postinstall/restore_apt_config: restoring apt configuration 2023-11-03 19:05:28,351 DEBUG subiquitycore.utils:154 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3402.13"}}', 'in-target', '-t', '/target', '--', 'apt-get', 'update'] 2023-11-03 19:05:37,208 ERROR root:30 finish: subiquity/Install/install/postinstall/restore_apt_config: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3402.13"}}', 'in-target', '-t', '/target', '--', 'apt-get', 'update']' returned non-zero exit status 100. 2023-11-03 19:05:37,209 ERROR root:30 finish: subiquity/Install/install/postinstall: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3402.13"}}', 'in-target', '-t', '/target', '--', 'apt-get', 'update']' returned non-zero exit status 100. 2023-11-03 19:05:37,209 DEBUG subiquity.common.errorreport:394 generating crash report 2023-11-03 19:05:37,261 INFO subiquity.common.errorreport:415 saving crash report 'install failed crashed with CalledProcessError' to /var/crash/1699038337.209933758.install_fail.crash 2023-11-03 19:05:37,261 ERROR root:30 finish: subiquity/Install/install: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3402.13"}}', 'in-target', '-t', '/target', '--', 'apt-get', 'update']' returned non-zero exit status 100. 2023-11-03 19:05:37,262 INFO root:30 start: subiquity/ErrorReporter/1699038337.209933758.install_fail/add_info: 2023-11-03 19:05:37,262 ERROR subiquity.server.server:415 top level error Traceback (most recent call last): File "/snap/ubuntu-budgie-installer/41/bin/subiquity/subiquity/server/controllers/shutdown.py", line 73, in _wait_install await self.app.controllers.Install.install_task subprocess.CalledProcessError: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3402.13"}}', 'in-target', '-t', '/target', '--', 'apt-get', 'update']' returned non-zero exit status 100. 2023-11-03 19:05:37,263 ERROR subiquity.server.server:415 top level error Traceback (most recent call last): File "/snap/ubuntu-budgie-installer/41/bin/subiquity/subiquity/server/controllers/shutdown.py", line 73, in _wait_install await self.app.controllers.Install.install_task subprocess.CalledProcessError: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3402', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-budgie-installer/41/bin/subiquity/bin:/snap/ubuntu-budgie-installer/41/bin/subiquity/usr/bin:/snap/ubuntu-budgie-installer/41/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/usr/sbin:/snap/ubuntu-budgie-installer/41/usr/bin:/snap/ubuntu-budgie-installer/41/sbin:/snap/ubuntu-budgie-installer/41/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-budgie-installer/41/lib/python3.10/site-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/dist-packages:/snap/ubuntu-budgie-installer/41/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-budgie-installer/41', '--', '/snap/ubuntu-budgie-installer/41/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3402.13"}}', 'in-target', '-t', '/target', '--', 'apt-get', 'update']' returned non-zero exit status 100. 2023-11-03 19:05:37,264 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "ERROR", "confirming_tty": "/dev/tty1", "error": {"state": "INCOMPL... 2023-11-03 19:05:37,265 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "ERROR", "confirming_tty": "/dev/tty1", "error": {"state": "INCOMPL... 2023-11-03 19:05:37,267 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-03 19:05:37,267 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-03 19:05:38,404 INFO root:30 finish: subiquity/ErrorReporter/1699038337.209933758.install_fail/add_info: SUCCESS: written to /var/crash/1699038337.209933758.install_fail.crash 2023-11-03 19:07:01,985 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:07:02,005 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:07:02,005 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:07:02,940 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:07:02,949 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:07:02,950 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'NOVA-K6LAQ9', 'no status')]} 2023-11-03 19:07:02,950 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:07:02,976 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:07:03,487 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:07:03,496 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:07:03,496 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:07:04,212 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:07:04,228 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:07:04,229 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'NOVA-K6LAQ9', 'no status')]} 2023-11-03 19:07:04,229 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:07:04,236 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:07:04,988 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:07:05,012 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:07:05,013 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:07:05,712 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:07:05,720 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:07:05,720 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Vodafone-UwWV', 'no status')]} 2023-11-03 19:07:05,721 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:07:05,732 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:07:06,489 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:07:06,516 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:07:06,516 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:07:09,404 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:07:09,411 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:07:09,412 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Vodafone-UwWV', 'no status')]} 2023-11-03 19:07:09,412 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:07:09,418 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:09:03,011 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:09:03,018 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:09:03,018 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:09:03,836 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:09:03,844 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:09:03,844 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status')]} 2023-11-03 19:09:03,845 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:09:03,851 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:11:03,990 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:11:04,017 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:11:04,017 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:11:04,828 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:11:04,836 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:11:04,837 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status')]} 2023-11-03 19:11:04,837 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:11:04,844 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:11:05,492 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:11:05,500 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:11:05,501 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:11:06,096 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:11:06,104 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:11:06,105 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status')]} 2023-11-03 19:11:06,105 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:11:06,125 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:11:08,582 ERROR root:30 finish: subiquity/Meta/status_GET: FAIL: cancelled 2023-11-03 19:11:08,587 ERROR root:30 finish: subiquity/Meta/status_GET: FAIL: cancelled 2023-11-03 19:13:04,007 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:13:04,028 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:13:04,029 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:13:04,940 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:13:04,948 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:13:04,949 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'NOVA-K6LAQ9', 'no status')]} 2023-11-03 19:13:04,949 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:13:04,960 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:15:05,012 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:15:05,037 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:15:05,037 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:15:07,912 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:15:07,921 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:15:07,921 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Vodafone-UwWV', 'no status')]} 2023-11-03 19:15:07,921 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:15:07,928 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:15:08,120 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:15:08,141 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:15:08,141 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:15:08,944 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:15:08,954 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:15:08,954 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Vodafone-UwWV', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status')]} 2023-11-03 19:15:08,955 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:15:08,984 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:15:09,622 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:15:09,630 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:15:09,630 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:15:10,232 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:15:10,253 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:15:10,253 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Vodafone-UwWV', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status')]} 2023-11-03 19:15:10,253 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:15:10,277 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:17:05,984 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:17:06,008 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:17:06,009 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:17:06,708 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:17:06,715 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:17:06,716 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status')]} 2023-11-03 19:17:06,716 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:17:06,736 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:19:06,991 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:19:07,013 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:19:07,013 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:19:07,944 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:19:07,964 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:19:07,965 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'NOVA-K6LAQ9', 'no status')]} 2023-11-03 19:19:07,965 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:19:07,984 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:19:08,492 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:19:08,504 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:19:08,504 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:19:11,388 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:19:11,409 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:19:11,409 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'NOVA-K6LAQ9', 'no status')]} 2023-11-03 19:19:11,409 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:19:11,416 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:21:07,992 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:21:08,021 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:21:08,021 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:21:08,932 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:21:08,949 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:21:08,949 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status')]} 2023-11-03 19:21:08,949 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:21:08,973 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:23:09,024 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:23:09,045 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:23:09,045 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:23:09,961 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:23:09,976 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:23:09,977 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Vodafone-UwWV', 'no status')]} 2023-11-03 19:23:09,977 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:23:09,988 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:23:10,526 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:23:10,549 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:23:10,549 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:23:11,244 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:23:11,268 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:23:11,268 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Vodafone-UwWV', 'no status'), (b'Vodafone-UwWV', 'no status')]} 2023-11-03 19:23:11,269 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:23:11,280 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:25:09,023 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:25:09,052 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:25:09,052 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:25:09,844 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:25:09,873 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:25:09,873 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status')]} 2023-11-03 19:25:09,873 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:25:09,893 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:27:09,995 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:27:10,017 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:27:10,017 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:27:12,888 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:27:12,895 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:27:12,896 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status')]} 2023-11-03 19:27:12,896 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:27:12,920 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:27:13,098 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:27:13,104 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:27:13,105 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:27:13,936 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:27:13,956 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:27:13,957 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Vodafone-UwWV', 'no status'), (b'NOVA-K6LAQ9', 'no status')]} 2023-11-03 19:27:13,957 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:27:13,976 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:27:14,599 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:27:14,621 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:27:14,621 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:27:15,200 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:27:15,225 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:27:15,225 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Vodafone-UwWV', 'no status'), (b'NOVA-K6LAQ9', 'no status')]} 2023-11-03 19:27:15,225 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:27:15,249 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:29:10,985 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:29:10,992 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:29:10,993 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:29:11,920 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:29:11,948 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:29:11,949 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status')]} 2023-11-03 19:29:11,949 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:29:11,956 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:29:12,486 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:29:12,513 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:29:12,513 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:29:13,204 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:29:13,212 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:29:13,212 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status')]} 2023-11-03 19:29:13,212 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:29:13,236 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:29:13,988 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:29:14,013 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:29:14,013 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:29:16,884 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:29:16,908 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:29:16,909 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'Vodafone-UwWV', 'no status')]} 2023-11-03 19:29:16,909 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:29:16,924 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:29:17,093 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:29:17,117 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:29:17,117 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:29:18,144 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:29:18,161 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:29:18,161 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'Vodafone-UwWV', 'no status')]} 2023-11-03 19:29:18,161 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:29:18,185 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:31:12,024 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:31:12,031 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:31:12,031 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:31:13,076 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:31:13,101 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:31:13,101 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status')]} 2023-11-03 19:31:13,101 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:31:13,124 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:33:13,012 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:33:13,041 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:33:13,041 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:33:13,945 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:33:13,953 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:33:13,954 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status')]} 2023-11-03 19:33:13,954 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:33:13,980 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:33:14,514 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:33:14,521 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:33:14,521 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:33:15,232 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:33:15,252 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:33:15,253 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status')]} 2023-11-03 19:33:15,253 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:33:15,277 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:35:13,985 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:35:14,009 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:35:14,009 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:35:16,884 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:35:16,904 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:35:16,905 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'Vodafone-UwWV', 'no status')]} 2023-11-03 19:35:16,905 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:35:16,912 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:35:17,092 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:35:17,117 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:35:17,117 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:35:18,152 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:35:18,160 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:35:18,161 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'Vodafone-UwWV', 'no status'), (b'NOVA-K6LAQ9', 'no status')]} 2023-11-03 19:35:18,161 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:35:18,184 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:37:14,990 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:37:15,012 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:37:15,012 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:37:15,936 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:37:15,944 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:37:15,944 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'NOVA-K6LAQ9', 'no status')]} 2023-11-03 19:37:15,944 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:37:15,964 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:39:16,009 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:39:16,017 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:39:16,017 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:39:16,837 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:39:16,864 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:39:16,865 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status')]} 2023-11-03 19:39:16,865 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:39:16,880 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:39:17,511 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:39:17,537 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:39:17,537 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:39:18,116 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:39:18,132 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:39:18,133 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status')]} 2023-11-03 19:39:18,133 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:39:18,140 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:39:19,013 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:39:19,037 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:39:19,037 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:39:21,920 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:39:21,952 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:39:21,953 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status')]} 2023-11-03 19:39:21,953 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:39:21,959 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:39:22,128 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:39:22,149 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:39:22,149 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:39:23,300 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:39:23,310 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:39:23,310 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'Vodafone-UwWV', 'no status')]} 2023-11-03 19:39:23,310 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:39:23,318 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:39:23,629 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:39:23,653 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:39:23,653 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:39:24,580 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:39:24,590 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:39:24,591 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'Vodafone-UwWV', 'no status')]} 2023-11-03 19:39:24,591 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:39:24,613 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:41:16,041 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:41:16,065 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:41:16,065 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2023-11-03 19:41:16,992 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:41:16,999 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-11-03 19:41:17,000 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Galaxy S21 5Gbdb5', 'no status'), (b'Vodafone-UwWV', 'no status')]} 2023-11-03 19:41:17,000 DEBUG subiquitycore.utils:79 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-11-03 19:41:17,021 DEBUG subiquitycore.utils:98 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0