ubuntuusers.de

via DuckDuckGo

pxe-Boot mit Autoinstallation

Status: Ungelöst | Ubuntu-Version: Ubuntu 24.04 (Noble Numbat)
Antworten |

leof.

Anmeldungsdatum:
8. Februar 2007

Beiträge: 309

Wohnort: Ettlingen
Zitieren
14. November 2024 22:03

Hallo zusammen,

ich möchte im Netzwerk Ubuntu per PXE booten und eine Autoinstallation durchführen lassen. Der PXE-Boot funktioniert auch, nur wird leider nicht die yaml-Konfigurationsdatei dem Installer direkt mit übergeben. Wenn man während des Installationsprozesses den Pfad http://172.20.0.2/user-data angibt, wird sie geladen und alles läuft prima.

Was habe ich gemacht: Nach dieser Anleitung habe ich ipxe, dnsmasq (DHCP- und TFTP-Server), nfs-kernel-server (NFS-Server) und ngingx (Webserver) installiert.

Meine /pxeboot/config/boot.ipxe sieht so aus: 

set server_ip  172.20.0.2
set root_path  /pxeboot

menu Select an OS to boot
item ubuntu-24.04-desktop-amd64         Install Ubuntu Desktop 24.04 LTS
item ubuntu-24.04-server-amd64         Install Ubuntu Server 24.04 LTS

#choose --default ubuntu-24.04-server-amd64 --timeout 0 option && goto ${option}
choose --default exit --timeout 1000 option && goto ${option}

:ubuntu-24.04-desktop-amd64
set os_root os-images/ubuntu-24.04-desktop-amd64
kernel tftp://${server_ip}/${os_root}/casper/vmlinuz
initrd tftp://${server_ip}/${os_root}/casper/initrd
imgargs vmlinuz initrd=initrd boot=casper maybe-ubiquity netboot=nfs ip=dhcp nfsroot=${server_ip}:${root_path}/${os_root} autoinstall "ds=nocloud-net;s=http://${server_ip}" debug
boot

In /var/www/ ligt die Datei meta-data: 

instance-id: iid-local01
local-hostname: ubuntu

und die user-data (einache Konfig zum Testen): 

#cloud-config
autoinstall:
  version: 1
  identity:
    hostname: ubuntu
    username: Test
    password: password: '$GEHEIMER HASH'

Habt ihr eine Idee, woran das liegt? Wo finde ich vllt. ein Log, in dem ich sehen kann, warum die Autoinstallationsdatei nicht direkt geladen wird?

Danke und liebe Grüße!

TNTMaster

Anmeldungsdatum:
30. Juli 2009

Beiträge: 876
Zitieren
15. November 2024 04:51 (zuletzt bearbeitet: 15. November 2024 04:53) 
    password: password: '$GEHEIMER HASH'

Ist da nicht ein password: zuviel?

Ersetze 

"ds=nocloud-net;s=http://${server_ip}"

mit 

cloud-config-url=http://${server_ip}/autoinstall.yaml

Die Datei autoinstall.yaml muß den selben Inhalt haben wie user-data. So läuft bei mir die Installation automatisch durch. Du kannst auch noch 

nosplash noplymouth

als Bootoptionen einfügen, um alle Meldungen beim Booten anzuzeigen.

Gruß TNT

leof.

(Themenstarter)

Anmeldungsdatum:
8. Februar 2007

Beiträge: 309

Wohnort: Ettlingen
Zitieren
15. November 2024 15:45

Hallo TNT, danke...

ja - das password: war zu viel. Ist mir beim Löschen des Hashs passiert.

Danke dir für eine Tipps. Leider hat auch das keine Abhilfe geschaffen. Ich habe hier einmal alle Log-Dateien hoch geladen, die ich von dem zu installierenden Client kopiert habe. Das Ubuntu bootet und der Installer startet mit der Auswahl von Sprache, Tastaturlayout, Ubuntu testen/installieren und dann kann ich die Datei autoinstall.yaml unter Angabe der URL händisch eingeben...

In der dmesg sieht man (dmesg | grep install), dass der Befehlt mit der cloud-config-url korrekt ankommt, oder? Hast du noch eine Idee woran es liegen kann? Vielleicht stehe ich auch nur auf dem Schlauch 😢

Danke und liebe Grüße Leo

TNTMaster

Anmeldungsdatum:
30. Juli 2009

Beiträge: 876
Zitieren
15. November 2024 16:33

In der dmesg sieht man (dmesg | grep install), dass der Befehlt mit der cloud-config-url korrekt ankommt, oder?

Hier ist aber die alte config aktiv. Falsche logs kopiert?

[    0.000000] kernel: Command line: vmlinuz initrd=initrd boot=casper maybe-ubiquity netboot=nfs ip=dhcp nfsroot=172.20.0.2:/pxeboot/os-images/ubuntu-24.04-desktop-amd64 autoinstall "ds=nocloud-net;s=http://172.20.0.2" debug

leof.

(Themenstarter)

Anmeldungsdatum:
8. Februar 2007

Beiträge: 309

Wohnort: Ettlingen
Zitieren
15. November 2024 20:10

Ja... sorry. mein Fehler. Habe die richtigen Dateien zur Verfügung gestellt... DANK DIR!! Weiß echt nicht weiter...

TNTMaster

Anmeldungsdatum:
30. Juli 2009

Beiträge: 876
Zitieren
15. November 2024 20:32 
[    0.000000] kernel: Command line: ... "cloud-config-url=http://172.20.0.2/autoinstall.yaml"

Mach die Gänsefüßchen noch weg, sonst wird das nichts.

leof.

(Themenstarter)

Anmeldungsdatum:
8. Februar 2007

Beiträge: 309

Wohnort: Ettlingen
Zitieren
18. November 2024 07:00

Guten Morgen,

leider hat das auch nichts gebracht...

Habe die Log-Dateien wieder aktualisiert. Muss die autoinstall.yaml noch immer von Hand bei der Installation angeben.

Siehst du noch etwas?

Danke und liebe Grüße

Leo

TNTMaster

Anmeldungsdatum:
30. Juli 2009

Beiträge: 876
Zitieren
18. November 2024 17:27

aus cloud-init.log:

2024-11-18 05:40:16,327 - main.py[WARNING]: contents of 'http://172.20.0.2/autoinstall.yaml' did not start with b'#cloud-config'

steht ja eindeutig da: Die 1. Zeile in der autoinstall.yaml muß #cloud-config lauten, und zur Erinnerung: autoinstall.yaml und user-data müssen den gleichen Inhalt haben, so zumindest hab ich das verstanden. Verwendest du hier eine andere config als du oben angegeben hast?

leof.

(Themenstarter)

Anmeldungsdatum:
8. Februar 2007

Beiträge: 309

Wohnort: Ettlingen
Zitieren
10. Dezember 2024 19:45 (zuletzt bearbeitet: 10. Dezember 2024 20:01)

Hallo TNTMaster,

danke für deine Hilfe. Dass die autoinstall.yaml und user-data den gleichen Inhalt haben mussten, war mir neu. Auf jeden Fall hat seit dem alles gut geklappt.

Allerdings gibt es seit heute ca. 14 Uhr Probleme. Wollte 10 Rechner installieren. Die ersten beiden Rechner liefen noch ohne Probleme durch, bei den restlichen 8 kam zu einem Fehler. Seit dem bekomme ich diese auch nicht mehr weg. Habe auch mal den PXE-Server auf den Snapshot zurück gesetzt, wo noch alles lief. Gleicher Fehler. Ich werde aus dem Log nicht so richtig schlau. Hänge den /var/log/installer/subiquity-server-debug.log hier mal an...

Hat übrigens nichts mit dem Autoinstall zu tun. Gleicher Fehler kommt auch, wenn ich über pxe-Boot starte und den Installer dann händisch mit den Infos "füttere".

Hast du nochmal eine Idee?

2024-12-10 18:33:25,579 INFO subiquity:201 Starting Subiquity server revision 237 of snap /snap/ubuntu-desktop-bootstrap/237 of version 0+git.66bf8d183
2024-12-10 18:33:25,579 INFO subiquity:205 Arguments passed: ['/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/subiquity/cmd/server.py', '--use-os-prober', '--storage-version=2', '--postinst-hooks-dir=/snap/ubuntu-desktop-bootstrap/237/etc/subiquity/postinst.d']
2024-12-10 18:33:25,579 DEBUG subiquity:206 Kernel commandline: CommandLineParams(_raw='vmlinuz initrd=initrd boot=casper maybe-ubiquity netboot=nfs ip=dhcp nfsroot=172.20.0.2:/pxeboot/os-images/ubuntu-24.04-desktop-amd64 cloud-config-url=http://172.20.0.2/autoinstall.yaml autoinstall debug ---\n', _tokens={'autoinstall', 'vmlinuz', 'debug', '---', 'maybe-ubiquity'}, _values={'initrd': 'initrd', 'boot': 'casper', 'netboot': 'nfs', 'ip': 'dhcp', 'nfsroot': '172.20.0.2:/pxeboot/os-images/ubuntu-24.04-desktop-amd64', 'cloud-config-url': 'http://172.20.0.2/autoinstall.yaml'})
2024-12-10 18:33:25,579 DEBUG subiquity:207 Environment: environ({'SNAP_REVISION': '237', 'SNAP_REAL_HOME': '/root', 'SNAP_USER_COMMON': '/root/snap/ubuntu-desktop-bootstrap/common', 'PATH_ORIG': '/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', 'MEMORY_PRESSURE_WRITE': 'c29tZSAyMDAwMDAgMjAwMDAwMAA=', 'SNAP_INSTANCE_KEY': '', 'SNAP_EUID': '0', 'PWD': '/snap/ubuntu-desktop-bootstrap/237/bin/subiquity', 'SYSTEMD_EXEC_PID': '3609', 'SNAP_CONTEXT': 'LYdWwxa-zjhBzs9NBXfr1b2eXS5ZUXdCtYdYLZvvaDJIXVTxNIz0', 'LANG': 'C.UTF-8', 'MEMORY_PRESSURE_WATCH': '/sys/fs/cgroup/system.slice/snap.ubuntu-desktop-bootstrap.subiquity-server.service/memory.pressure', 'SNAP_ARCH': 'amd64', 'PY3OR2_PYTHON': '/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', 'SNAP_INSTANCE_NAME': 'ubuntu-desktop-bootstrap', 'SNAP_USER_DATA': '/root/snap/ubuntu-desktop-bootstrap/237', 'INVOCATION_ID': '04c0286031d445d09bbd868736a04970', '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-desktop-bootstrap/237/lib:/snap/ubuntu-desktop-bootstrap/237/usr/lib:/snap/ubuntu-desktop-bootstrap/237/lib/x86_64-linux-gnu:/snap/ubuntu-desktop-bootstrap/237/usr/lib/x86_64-linux-gnu', 'PYTHONPATH_ORIG': '', 'PYTHONPATH': '/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', 'USER': 'root', 'PYTHON': '/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', 'PYTHONIOENCODING': 'utf-8', 'SNAP': '/snap/ubuntu-desktop-bootstrap/237', 'SNAP_COMMON': '/var/snap/ubuntu-desktop-bootstrap/common', 'SNAP_VERSION': '0+git.66bf8d183', 'DISPLAY': ':0', 'SHLVL': '1', 'SNAP_LIBRARY_PATH': '/var/lib/snapd/lib/gl:/var/lib/snapd/lib/gl32:/var/lib/snapd/void', 'SNAP_COOKIE': 'LYdWwxa-zjhBzs9NBXfr1b2eXS5ZUXdCtYdYLZvvaDJIXVTxNIz0', 'SNAP_DATA': '/var/snap/ubuntu-desktop-bootstrap/237', 'LD_LIBRARY_PATH': '/var/lib/snapd/lib/gl:/var/lib/snapd/lib/gl32:/var/lib/snapd/void:/snap/ubuntu-desktop-bootstrap/237/lib:/snap/ubuntu-desktop-bootstrap/237/usr/lib:/snap/ubuntu-desktop-bootstrap/237/lib/x86_64-linux-gnu:/snap/ubuntu-desktop-bootstrap/237/usr/lib/x86_64-linux-gnu', 'SNAP_NAME': 'ubuntu-desktop-bootstrap', 'JOURNAL_STREAM': '8:20784', 'PATH': '/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', 'SUBIQUITY_ROOT': '/snap/ubuntu-desktop-bootstrap/237/bin/subiquity', 'OLDPWD': '/var/snap/ubuntu-desktop-bootstrap/237', '_': '/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', 'APPORT_DATA_DIR': '/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/share/apport'})
2024-12-10 18:33:25,579 DEBUG asyncio:54 Using selector: EpollSelector
2024-12-10 18:33:25,580 DEBUG subiquitycore.prober:33 Prober() init finished, data:None
2024-12-10 18:33:25,628 DEBUG curtin:120 Running command ['dpkg', '--print-architecture'] with allowed return codes [0] (capture=True)
2024-12-10 18:33:25,635 DEBUG subiquitycore.netplan:113 config for enx3c18a0162c27 = {'dhcp4': True, 'match': {'macaddress': '3c:18:a0:16:2c:27'}, 'set-name': 'enx3c18a0162c27'}
2024-12-10 18:33:25,636 DEBUG subiquitycore.netplan:113 config for enx3c18a0162c27 = {'dhcp4': True, 'dhcp-identifier': 'mac', 'critical': True, 'nameservers': {'addresses': ['8.8.8.8']}}
2024-12-10 18:33:25,651 DEBUG subiquitycore.utils:77 run_command called: ['dpkg-query', '-W', '-f=${Version}', 'cloud-init']
2024-12-10 18:33:25,670 DEBUG subiquitycore.utils:96 run_command ['dpkg-query', '-W', '-f=${Version}', 'cloud-init'] exited with code 0
2024-12-10 18:33:25,671 DEBUG subiquity.cloudinit:58 cloud-init version: 24.1.3
2024-12-10 18:33:25,671 DEBUG subiquitycore.utils:119 arun_command called: ['cloud-init', 'status', '--wait', '--format=json']
2024-12-10 18:33:26,478 DEBUG curtin.reporting.start.subiquity/Meta/status_GET:45 start: subiquity/Meta/status_GET: 
2024-12-10 18:33:26,479 DEBUG curtin.reporting.finish.subiquity/Meta/status_GET:45 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "CLOUD_INIT_WAIT", "confirming_tty": "", "error": null, "nonreporta...
2024-12-10 18:33:26,674 DEBUG subiquitycore.utils:133 arun_command ['cloud-init', 'status', '--wait', '--format=json'] exited with code 0
2024-12-10 18:33:26,674 DEBUG subiquity.server.server:880 waited 1.0234239101409912s for cloud-init
2024-12-10 18:33:26,674 DEBUG subiquity.server.server:881 cloud-init status: 'done'
2024-12-10 18:33:26,675 DEBUG curtin.reporting.start.subiquity/load_cloud_config:45 start: subiquity/load_cloud_config: 
2024-12-10 18:33:26,675 DEBUG subiquity.cloudinit:38 Loaded cloud config from /run/cloud-init/combined-cloud-config.json
2024-12-10 18:33:26,675 DEBUG curtin.reporting.start.subiquity/load_cloud_config/extract_autoinstall:45 start: subiquity/load_cloud_config/extract_autoinstall: 
2024-12-10 18:33:26,675 DEBUG curtin.reporting.start.subiquity/load_cloud_config/extract_autoinstall:45 start: subiquity/load_cloud_config/extract_autoinstall: 
2024-12-10 18:33:26,683 DEBUG subiquitycore.utils:119 arun_command called: ['cloud-init', 'schema', '--system']
2024-12-10 18:33:27,482 DEBUG curtin.reporting.start.subiquity/Meta/status_GET:45 start: subiquity/Meta/status_GET: 
2024-12-10 18:33:27,482 DEBUG curtin.reporting.finish.subiquity/Meta/status_GET:45 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "CLOUD_INIT_WAIT", "confirming_tty": "", "error": null, "nonreporta...
2024-12-10 18:33:27,786 DEBUG subiquitycore.utils:133 arun_command ['cloud-init', 'schema', '--system'] exited with code 0
2024-12-10 18:33:27,786 DEBUG curtin.reporting.finish.subiquity/load_cloud_config/extract_autoinstall:45 finish: subiquity/load_cloud_config/extract_autoinstall: SUCCESS: 
2024-12-10 18:33:27,786 DEBUG subiquity.server.server:865 autoinstall found in cloud-config
2024-12-10 18:33:27,790 DEBUG curtin.reporting.finish.subiquity/load_cloud_config:45 finish: subiquity/load_cloud_config: SUCCESS: 
2024-12-10 18:33:27,790 DEBUG subiquitycore.utils:77 run_command called: ['ssh-keygen', '-lf', '/home/installer/.ssh/authorized_keys']
2024-12-10 18:33:27,794 DEBUG subiquitycore.utils:96 run_command ['ssh-keygen', '-lf', '/home/installer/.ssh/authorized_keys'] exited with code 255
2024-12-10 18:33:27,794 DEBUG subiquitycore.ssh:53 ssh-keygen -lf /home/installer/.ssh/authorized_keys failed '/home/installer/.ssh/authorized_keys is not a public key file.\r\n'
2024-12-10 18:33:27,795 DEBUG subiquitycore.utils:77 run_command called: chpasswd
2024-12-10 18:33:27,917 DEBUG subiquitycore.utils:96 run_command chpasswd exited with code 0
2024-12-10 18:33:27,918 DEBUG curtin.reporting.start.subiquity/load_autoinstall_config:45 start: subiquity/load_autoinstall_config: 
2024-12-10 18:33:27,918 DEBUG subiquity.server.server:703 load_autoinstall_config only_early True file /autoinstall.yaml
2024-12-10 18:33:27,918 DEBUG curtin.reporting.start.subiquity/load_autoinstall_config/read_config:45 start: subiquity/load_autoinstall_config/read_config: 
2024-12-10 18:33:27,922 DEBUG curtin.reporting.finish.subiquity/load_autoinstall_config/read_config:45 finish: subiquity/load_autoinstall_config/read_config: SUCCESS: 
2024-12-10 18:33:27,922 DEBUG curtin.reporting.start.subiquity/Reporting/load_autoinstall_data:45 start: subiquity/Reporting/load_autoinstall_data: 
2024-12-10 18:33:27,923 DEBUG curtin.reporting.finish.subiquity/Reporting/load_autoinstall_data:45 finish: subiquity/Reporting/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,923 DEBUG root:38 start: subiquity/Error/load_autoinstall_data: 
2024-12-10 18:33:27,924 DEBUG root:38 finish: subiquity/Error/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,924 INFO root:38 start: subiquity/core_validation: 
2024-12-10 18:33:27,924 INFO root:38 start: subiquity/core_validation/top_level_keys: 
2024-12-10 18:33:27,924 INFO root:38 finish: subiquity/core_validation/top_level_keys: SUCCESS: 
2024-12-10 18:33:27,924 INFO root:38 finish: subiquity/core_validation: SUCCESS: 
2024-12-10 18:33:27,925 DEBUG root:38 start: subiquity/Early/load_autoinstall_data: 
2024-12-10 18:33:27,925 DEBUG root:38 finish: subiquity/Early/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,925 INFO root:38 finish: subiquity/load_autoinstall_config: SUCCESS: 
2024-12-10 18:33:27,925 INFO root:38 start: subiquity/load_autoinstall_config: 
2024-12-10 18:33:27,925 DEBUG subiquity.server.server:703 load_autoinstall_config only_early False file /autoinstall.yaml
2024-12-10 18:33:27,925 INFO root:38 start: subiquity/load_autoinstall_config/read_config: 
2024-12-10 18:33:27,929 INFO root:38 finish: subiquity/load_autoinstall_config/read_config: SUCCESS: 
2024-12-10 18:33:27,929 DEBUG root:38 start: subiquity/Early/load_autoinstall_data: 
2024-12-10 18:33:27,929 DEBUG root:38 finish: subiquity/Early/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,929 DEBUG root:38 start: subiquity/Reporting/load_autoinstall_data: 
2024-12-10 18:33:27,930 DEBUG root:38 finish: subiquity/Reporting/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,930 DEBUG root:38 start: subiquity/Error/load_autoinstall_data: 
2024-12-10 18:33:27,930 DEBUG root:38 finish: subiquity/Error/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,930 DEBUG root:38 start: subiquity/Userdata/load_autoinstall_data: 
2024-12-10 18:33:27,931 DEBUG cloudinit.util:1599 Reading from /snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False)
2024-12-10 18:33:27,931 DEBUG cloudinit.util:1608 Read 144875 bytes from /snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json
2024-12-10 18:33:27,933 DEBUG root:38 finish: subiquity/Userdata/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,933 DEBUG root:38 start: subiquity/Package/load_autoinstall_data: 
2024-12-10 18:33:27,934 DEBUG root:38 finish: subiquity/Package/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,934 DEBUG root:38 start: subiquity/Debconf/load_autoinstall_data: 
2024-12-10 18:33:27,934 DEBUG root:38 finish: subiquity/Debconf/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,934 DEBUG root:38 start: subiquity/Locale/load_autoinstall_data: 
2024-12-10 18:33:27,935 DEBUG root:38 finish: subiquity/Locale/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,935 DEBUG root:38 start: subiquity/Refresh/load_autoinstall_data: 
2024-12-10 18:33:27,935 DEBUG root:38 finish: subiquity/Refresh/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,935 DEBUG root:38 start: subiquity/Kernel/load_autoinstall_data: 
2024-12-10 18:33:27,935 DEBUG root:38 finish: subiquity/Kernel/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,935 DEBUG root:38 start: subiquity/Integrity/load_autoinstall_data: 
2024-12-10 18:33:27,935 DEBUG root:38 finish: subiquity/Integrity/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,935 DEBUG root:38 start: subiquity/Keyboard/load_autoinstall_data: 
2024-12-10 18:33:27,936 DEBUG root:38 finish: subiquity/Keyboard/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,936 DEBUG root:38 start: subiquity/Zdev/load_autoinstall_data: 
2024-12-10 18:33:27,936 DEBUG root:38 finish: subiquity/Zdev/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,936 DEBUG root:38 start: subiquity/Source/load_autoinstall_data: 
2024-12-10 18:33:27,936 DEBUG root:38 finish: subiquity/Source/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,936 DEBUG root:38 start: subiquity/Network/load_autoinstall_data: 
2024-12-10 18:33:27,936 DEBUG root:38 finish: subiquity/Network/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,937 DEBUG root:38 start: subiquity/UbuntuPro/load_autoinstall_data: 
2024-12-10 18:33:27,937 DEBUG root:38 finish: subiquity/UbuntuPro/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,937 DEBUG root:38 start: subiquity/Proxy/load_autoinstall_data: 
2024-12-10 18:33:27,937 DEBUG root:38 finish: subiquity/Proxy/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,937 DEBUG root:38 start: subiquity/Mirror/load_autoinstall_data: 
2024-12-10 18:33:27,937 DEBUG root:38 finish: subiquity/Mirror/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,937 DEBUG root:38 start: subiquity/Filesystem/load_autoinstall_data: 
2024-12-10 18:33:27,937 DEBUG root:38 finish: subiquity/Filesystem/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,937 DEBUG root:38 start: subiquity/Identity/load_autoinstall_data: 
2024-12-10 18:33:27,937 DEBUG root:38 finish: subiquity/Identity/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,938 DEBUG root:38 start: subiquity/SSH/load_autoinstall_data: 
2024-12-10 18:33:27,938 DEBUG root:38 finish: subiquity/SSH/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,938 DEBUG root:38 start: subiquity/SnapList/load_autoinstall_data: 
2024-12-10 18:33:27,939 DEBUG root:38 finish: subiquity/SnapList/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,939 DEBUG root:38 start: subiquity/Ad/load_autoinstall_data: 
2024-12-10 18:33:27,939 DEBUG root:38 finish: subiquity/Ad/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,939 DEBUG root:38 start: subiquity/Codecs/load_autoinstall_data: 
2024-12-10 18:33:27,939 DEBUG root:38 finish: subiquity/Codecs/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,939 DEBUG root:38 start: subiquity/Drivers/load_autoinstall_data: 
2024-12-10 18:33:27,940 DEBUG root:38 finish: subiquity/Drivers/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,940 DEBUG root:38 start: subiquity/OEM/load_autoinstall_data: 
2024-12-10 18:33:27,940 DEBUG root:38 finish: subiquity/OEM/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,940 DEBUG root:38 start: subiquity/TimeZone/load_autoinstall_data: 
2024-12-10 18:33:27,985 DEBUG root:38 finish: subiquity/TimeZone/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,986 DEBUG root:38 start: subiquity/Install/load_autoinstall_data: 
2024-12-10 18:33:27,986 DEBUG root:38 finish: subiquity/Install/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,986 DEBUG root:38 start: subiquity/Updates/load_autoinstall_data: 
2024-12-10 18:33:27,987 DEBUG root:38 finish: subiquity/Updates/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,987 DEBUG root:38 start: subiquity/Late/load_autoinstall_data: 
2024-12-10 18:33:27,988 DEBUG root:38 finish: subiquity/Late/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,988 DEBUG root:38 start: subiquity/Shutdown/load_autoinstall_data: 
2024-12-10 18:33:27,988 DEBUG root:38 finish: subiquity/Shutdown/load_autoinstall_data: SUCCESS: 
2024-12-10 18:33:27,989 INFO root:38 finish: subiquity/load_autoinstall_config: SUCCESS: 
2024-12-10 18:33:27,989 DEBUG subiquitycore.core:118 starting controllers
2024-12-10 18:33:27,991 DEBUG subiquity.server.controllers.kernel:63 Using kernel linux-generic-hwe-24.04 due to /etc/subiquity/kernel-meta-package
2024-12-10 18:33:28,000 DEBUG subiquity.models.source:93 loaded 2 sources from '/cdrom/casper/install-sources.yaml'
2024-12-10 18:33:28,001 DEBUG probert.network:585 event for link_change: NEW {'ifindex': 1, 'flags': 65609, 'arptype': 772, 'family': 0, 'is_vlan': False, 'name': b'lo'}
2024-12-10 18:33:28,001 DEBUG probert.network:585 event for link_change: NEW {'ifindex': 2, 'flags': 4099, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'enp3s0'}
2024-12-10 18:33:28,001 DEBUG probert.network:585 event for link_change: NEW {'ifindex': 3, 'flags': 69699, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'enx3c18a0162c27'}
2024-12-10 18:33:28,001 DEBUG probert.network:585 event for link_change: NEW {'ifindex': 4, 'flags': 4099, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'wlp6s0'}
2024-12-10 18:33:28,001 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 1, 'flags': 128, 'family': 2, 'scope': 254, 'local': b'127.0.0.1/8'}
2024-12-10 18:33:28,001 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 3, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'172.20.0.106/24'}
2024-12-10 18:33:28,001 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 3, 'flags': 513, 'family': 2, 'scope': 0, 'local': b'172.20.0.105/24'}
2024-12-10 18:33:28,002 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 1, 'flags': 640, 'family': 10, 'scope': 254, 'local': b'::1'}
2024-12-10 18:33:28,002 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 3, 'flags': 128, 'family': 10, 'scope': 253, 'local': b'fe80::3e18:a0ff:fe16:2c27/64'}
2024-12-10 18:33:28,002 DEBUG probert.network:672 link_change NEW {'ifindex': 1, 'flags': 65609, 'arptype': 772, 'family': 0, 'is_vlan': False, 'name': b'lo'}
2024-12-10 18:33:28,103 DEBUG subiquitycore.models.network:477 new_link 1 lo lo
2024-12-10 18:33:28,103 DEBUG subiquitycore.models.network:479 ignoring based on type
2024-12-10 18:33:28,103 DEBUG probert.network:672 link_change NEW {'ifindex': 2, 'flags': 4099, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'enp3s0'}
2024-12-10 18:33:28,209 DEBUG subiquitycore.models.network:477 new_link 2 enp3s0 eth
2024-12-10 18:33:28,209 DEBUG subiquitycore.models.network:508 new_link 2 enp3s0 with config {}
2024-12-10 18:33:28,209 DEBUG root:38 start: subiquity/Network/_send_update: NEW enp3s0
2024-12-10 18:33:28,209 DEBUG root:38 finish: subiquity/Network/_send_update: SUCCESS: NEW enp3s0
2024-12-10 18:33:28,210 DEBUG probert.network:672 link_change NEW {'ifindex': 3, 'flags': 69699, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'enx3c18a0162c27'}
2024-12-10 18:33:28,313 DEBUG subiquitycore.models.network:477 new_link 3 enx3c18a0162c27 eth
2024-12-10 18:33:28,313 DEBUG subiquitycore.models.network:508 new_link 3 enx3c18a0162c27 with config {'dhcp4': True, 'match': {'macaddress': '3c:18:a0:16:2c:27'}, 'set-name': 'enx3c18a0162c27'}
2024-12-10 18:33:28,313 DEBUG root:38 start: subiquity/Network/_send_update: NEW enx3c18a0162c27
2024-12-10 18:33:28,314 DEBUG root:38 finish: subiquity/Network/_send_update: SUCCESS: NEW enx3c18a0162c27
2024-12-10 18:33:28,314 DEBUG probert.network:672 link_change NEW {'ifindex': 4, 'flags': 4099, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'wlp6s0'}
2024-12-10 18:33:28,416 DEBUG subiquitycore.models.network:477 new_link 4 wlp6s0 wlan
2024-12-10 18:33:28,417 DEBUG subiquitycore.models.network:508 new_link 4 wlp6s0 with config {}
2024-12-10 18:33:28,417 DEBUG subiquity.server.controllers.network:100 maybe_start_install_wpasupplicant
2024-12-10 18:33:28,417 DEBUG root:38 start: subiquity/Network/_send_update: NEW wlp6s0
2024-12-10 18:33:28,417 DEBUG root:38 finish: subiquity/Network/_send_update: SUCCESS: NEW wlp6s0
2024-12-10 18:33:28,417 DEBUG probert.network:717 addr_change NEW {'ifindex': 1, 'flags': 128, 'family': 2, 'scope': 254, 'local': b'127.0.0.1/8'}
2024-12-10 18:33:28,418 DEBUG probert.network:717 addr_change NEW {'ifindex': 3, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'172.20.0.106/24'}
2024-12-10 18:33:28,418 DEBUG root:38 start: subiquity/Network/_send_update: CHANGE enx3c18a0162c27
2024-12-10 18:33:28,418 DEBUG root:38 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enx3c18a0162c27
2024-12-10 18:33:28,418 DEBUG probert.network:717 addr_change NEW {'ifindex': 3, 'flags': 513, 'family': 2, 'scope': 0, 'local': b'172.20.0.105/24'}
2024-12-10 18:33:28,418 DEBUG root:38 start: subiquity/Network/_send_update: CHANGE enx3c18a0162c27
2024-12-10 18:33:28,418 DEBUG root:38 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enx3c18a0162c27
2024-12-10 18:33:28,418 DEBUG probert.network:717 addr_change NEW {'ifindex': 1, 'flags': 640, 'family': 10, 'scope': 254, 'local': b'::1'}
2024-12-10 18:33:28,419 DEBUG probert.network:717 addr_change NEW {'ifindex': 3, 'flags': 128, 'family': 10, 'scope': 253, 'local': b'fe80::3e18:a0ff:fe16:2c27/64'}
2024-12-10 18:33:28,419 DEBUG root:38 start: subiquity/Network/_send_update: CHANGE enx3c18a0162c27
2024-12-10 18:33:28,419 DEBUG root:38 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enx3c18a0162c27
2024-12-10 18:33:28,419 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 3}
2024-12-10 18:33:28,432 DEBUG pyroute2.ndb.125574248632896.sources.localhost:74 init
2024-12-10 18:33:28,432 DEBUG pyroute2.ndb.125574248632896.sources.localhost:384 starting the source
2024-12-10 18:33:28,433 DEBUG pyroute2.ndb.125574248632896.sources.localhost/nsmanager:74 init
2024-12-10 18:33:28,433 DEBUG pyroute2.ndb.125574248632896.sources.localhost:74 connecting
2024-12-10 18:33:28,433 DEBUG pyroute2.ndb.125574248632896.sources.localhost:74 loading
2024-12-10 18:33:28,433 DEBUG pyroute2.ndb.125574248632896.sources.localhost/nsmanager:384 starting the source
2024-12-10 18:33:28,434 DEBUG pyroute2.ndb.125574248632896.sources.localhost/nsmanager:74 connecting
2024-12-10 18:33:28,436 DEBUG pyroute2.ndb.125574248632896.sources.localhost/nsmanager:74 loading
2024-12-10 18:33:28,487 DEBUG pyroute2.ndb.125574248632896.sources.localhost:74 running
2024-12-10 18:33:28,488 DEBUG pyroute2.ndb.125574248632896.sources.localhost/nsmanager:74 running
2024-12-10 18:33:28,489 DEBUG pyroute2.ndb.125574248632896.sources.localhost:399 source shutdown
2024-12-10 18:33:28,489 DEBUG pyroute2.ndb.125574248632896.sources.localhost:374 sync
2024-12-10 18:33:28,489 DEBUG pyroute2.ndb.125574248632896.sources.localhost:369 shutdown handled by the main thread
2024-12-10 18:33:28,489 DEBUG pyroute2.ndb.125574248632896.sources.localhost:74 stopped
2024-12-10 18:33:28,489 DEBUG pyroute2.ndb.125574248632896.main:851 flush DB for the target localhost
2024-12-10 18:33:28,490 DEBUG pyroute2.ndb.125574248632896.sources.localhost/nsmanager:399 source shutdown
2024-12-10 18:33:28,490 DEBUG pyroute2.ndb.125574248632896.sources.localhost/nsmanager:374 sync
2024-12-10 18:33:28,490 DEBUG pyroute2.ndb.125574248632896.sources.localhost/nsmanager:369 shutdown handled by the main thread
2024-12-10 18:33:28,490 DEBUG pyroute2.ndb.125574248632896.sources.localhost/nsmanager:74 stopped
2024-12-10 18:33:28,490 DEBUG pyroute2.ndb.125574248632896.main:851 flush DB for the target localhost/nsmanager
2024-12-10 18:33:28,491 DEBUG subiquitycore.controllers.network:103 default routes True
2024-12-10 18:33:28,491 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 3}
2024-12-10 18:33:28,499 DEBUG pyroute2.ndb.125574248642592.sources.localhost:74 init
2024-12-10 18:33:28,500 DEBUG pyroute2.ndb.125574248642592.sources.localhost:384 starting the source
2024-12-10 18:33:28,500 DEBUG pyroute2.ndb.125574248642592.sources.localhost/nsmanager:74 init
2024-12-10 18:33:28,500 DEBUG pyroute2.ndb.125574248642592.sources.localhost:74 connecting
2024-12-10 18:33:28,500 DEBUG pyroute2.ndb.125574248642592.sources.localhost/nsmanager:384 starting the source
2024-12-10 18:33:28,500 DEBUG pyroute2.ndb.125574248642592.sources.localhost:74 loading
2024-12-10 18:33:28,500 DEBUG pyroute2.ndb.125574248642592.sources.localhost/nsmanager:74 connecting
2024-12-10 18:33:28,502 DEBUG pyroute2.ndb.125574248642592.sources.localhost/nsmanager:74 loading
2024-12-10 18:33:28,513 DEBUG pyroute2.ndb.125574248642592.sources.localhost:74 running
2024-12-10 18:33:28,514 DEBUG pyroute2.ndb.125574248642592.sources.localhost/nsmanager:74 running
2024-12-10 18:33:28,515 DEBUG pyroute2.ndb.125574248642592.sources.localhost:399 source shutdown
2024-12-10 18:33:28,515 DEBUG pyroute2.ndb.125574248642592.sources.localhost:374 sync
2024-12-10 18:33:28,515 DEBUG pyroute2.ndb.125574248642592.sources.localhost:369 shutdown handled by the main thread
2024-12-10 18:33:28,515 DEBUG pyroute2.ndb.125574248642592.sources.localhost:74 stopped
2024-12-10 18:33:28,515 DEBUG pyroute2.ndb.125574248642592.main:851 flush DB for the target localhost
2024-12-10 18:33:28,515 DEBUG pyroute2.ndb.125574248642592.sources.localhost/nsmanager:399 source shutdown
2024-12-10 18:33:28,521 DEBUG pyroute2.ndb.125574248642592.sources.localhost/nsmanager:374 sync
2024-12-10 18:33:28,521 DEBUG pyroute2.ndb.125574248642592.sources.localhost/nsmanager:369 shutdown handled by the main thread
2024-12-10 18:33:28,521 DEBUG pyroute2.ndb.125574248642592.sources.localhost/nsmanager:74 stopped
2024-12-10 18:33:28,521 DEBUG pyroute2.ndb.125574248642592.main:851 flush DB for the target localhost/nsmanager
2024-12-10 18:33:28,522 DEBUG subiquitycore.controllers.network:103 default routes True
2024-12-10 18:33:28,522 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'172.20.0.0/24', 'ifindex': 3}
2024-12-10 18:33:28,522 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'172.20.0.0/24', 'ifindex': 3}
2024-12-10 18:33:28,522 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'127.0.0.0/8', 'ifindex': 1}
2024-12-10 18:33:28,522 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'127.0.0.1', 'ifindex': 1}
2024-12-10 18:33:28,522 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'127.255.255.255', 'ifindex': 1}
2024-12-10 18:33:28,523 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'172.20.0.105', 'ifindex': 3}
2024-12-10 18:33:28,523 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'172.20.0.106', 'ifindex': 3}
2024-12-10 18:33:28,523 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'172.20.0.255', 'ifindex': 3}
2024-12-10 18:33:28,523 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 1, 'table': 254, 'dst': b'fe80::/64', 'ifindex': 3}
2024-12-10 18:33:28,523 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 2, 'table': 255, 'dst': b'::1', 'ifindex': 1}
2024-12-10 18:33:28,523 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 2, 'table': 255, 'dst': b'fe80::3e18:a0ff:fe16:2c27', 'ifindex': 3}
2024-12-10 18:33:28,523 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 5, 'table': 255, 'dst': b'ff00::/8', 'ifindex': 3}
2024-12-10 18:33:28,523 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_INTERFACE', 'ifindex': 4, 'ssids': []}
2024-12-10 18:33:28,544 DEBUG subiquitycore.core:121 controllers started
2024-12-10 18:33:28,544 INFO root:38 start: subiquity/apply_autoinstall_config: 
2024-12-10 18:33:28,545 DEBUG root:38 start: subiquity/Early/apply_autoinstall_config: 
2024-12-10 18:33:28,545 DEBUG root:38 finish: subiquity/Early/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:28,545 DEBUG root:38 start: subiquity/Reporting/apply_autoinstall_config: 
2024-12-10 18:33:28,545 DEBUG root:38 finish: subiquity/Reporting/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:28,545 DEBUG root:38 start: subiquity/Error/apply_autoinstall_config: 
2024-12-10 18:33:28,545 DEBUG root:38 finish: subiquity/Error/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:28,545 DEBUG root:38 start: subiquity/Userdata/apply_autoinstall_config: 
2024-12-10 18:33:28,546 DEBUG root:38 finish: subiquity/Userdata/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:28,546 DEBUG subiquity.models.subiquity:256 model userdata for postinstall stage is configured, to go {'ubuntu_pro', 'packages', 'ssh', 'snaplist', 'drivers', 'locale', 'identity'}
2024-12-10 18:33:28,546 DEBUG root:38 start: subiquity/Package/apply_autoinstall_config: 
2024-12-10 18:33:28,546 DEBUG root:38 finish: subiquity/Package/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:28,546 DEBUG subiquity.models.subiquity:256 model packages for postinstall stage is configured, to go {'ubuntu_pro', 'ssh', 'snaplist', 'drivers', 'locale', 'identity'}
2024-12-10 18:33:28,546 DEBUG root:38 start: subiquity/Debconf/apply_autoinstall_config: 
2024-12-10 18:33:28,546 DEBUG root:38 finish: subiquity/Debconf/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:28,546 DEBUG subiquity.models.subiquity:256 model debconf_selections for install stage is configured, to go {'source', 'keyboard', 'kernel', 'filesystem'}
2024-12-10 18:33:28,547 DEBUG root:38 start: subiquity/Locale/apply_autoinstall_config: 
2024-12-10 18:33:28,547 DEBUG root:38 finish: subiquity/Locale/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:28,547 DEBUG subiquity.models.subiquity:256 model locale for postinstall stage is configured, to go {'ubuntu_pro', 'ssh', 'snaplist', 'drivers', 'identity'}
2024-12-10 18:33:28,547 DEBUG root:38 start: subiquity/Refresh/apply_autoinstall_config: 
2024-12-10 18:33:28,547 DEBUG root:38 finish: subiquity/Refresh/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:28,547 DEBUG root:38 start: subiquity/Kernel/apply_autoinstall_config: 
2024-12-10 18:33:28,547 DEBUG root:38 finish: subiquity/Kernel/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:28,547 DEBUG subiquity.models.subiquity:256 model kernel for install stage is configured, to go {'source', 'keyboard', 'filesystem'}
2024-12-10 18:33:28,547 DEBUG root:38 start: subiquity/Integrity/apply_autoinstall_config: 
2024-12-10 18:33:28,547 DEBUG root:38 finish: subiquity/Integrity/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:28,548 DEBUG root:38 start: subiquity/Keyboard/apply_autoinstall_config: 
2024-12-10 18:33:28,548 DEBUG subiquitycore.utils:119 arun_command called: ['setupcon', '--save', '--force', '--keyboard-only']
2024-12-10 18:33:28,549 DEBUG subiquity.models.subiquity:256 model locale for postinstall stage is configured, to go {'ubuntu_pro', 'ssh', 'snaplist', 'drivers', 'identity'}
2024-12-10 18:33:28,549 DEBUG root:38 start: subiquity/Network/wait_for_initial_config: 
2024-12-10 18:33:28,549 DEBUG root:38 finish: subiquity/Network/wait_for_initial_config: SUCCESS: 
2024-12-10 18:33:28,550 DEBUG subiquitycore.utils:119 arun_command called: ['/usr/sbin/realm', 'discover']
2024-12-10 18:33:28,559 DEBUG root:38 start: subiquity/OEM/load_metapackages_list: 
2024-12-10 18:33:28,560 DEBUG root:38 start: subiquity/OEM/load_metapackages_list/wait_confirmation: 
2024-12-10 18:33:28,560 DEBUG root:38 start: subiquity/Install/install: 
2024-12-10 18:33:28,560 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:33:28,565 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:33:28,565 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 4}
2024-12-10 18:33:28,566 DEBUG subiquity.server.pkghelper:65 checking if wpasupplicant is available
2024-12-10 18:33:33,207 DEBUG subiquity.server.pkghelper:71 wpasupplicant already installed
2024-12-10 18:33:33,236 DEBUG root:38 start: subiquity/Filesystem/_probe: 
2024-12-10 18:33:33,237 DEBUG subiquity.server.controllers.network:112 wlan_support_install_finished PackageInstallState.DONE
2024-12-10 18:33:33,238 DEBUG root:38 start: subiquity/Filesystem/_probe/probe_once: restricted=False
2024-12-10 18:33:33,241 DEBUG asyncio:54 Using selector: EpollSelector
2024-12-10 18:33:33,241 INFO root:38 start: subiquity/Meta/status_GET: 
2024-12-10 18:33:33,243 INFO root:38 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "nonreportable_erro...
2024-12-10 18:33:33,243 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:33:33,248 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:33:33,248 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 4, 'ssids': [(b'XXX-SCHULNETZ', 'no status'), (b'Media', 'no status'), (b'Media', 'no status'), (b'', 'no status'), (b'Media', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'Media', 'no status'), (b'', 'no status'), (b'', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'BvSS-Surface', 'no status'), (b'BvSS-Mobil', 'no status'), (b'WRS-offen', 'no status'), (b'WRS-Mobil', 'no status'), (b'', 'no status'), (b'Media', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'BvSS-offen', 'no status'), (b'', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'WRS-offen', 'no status'), (b'WRS-Mobil', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'BvSS-Surface', 'no status'), (b'BvSS-offen', 'no status')]}
2024-12-10 18:33:33,248 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:33:33,252 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:33:33,253 DEBUG probert.network:585 event for link_change: CHANGE {'ifindex': 4, 'flags': 4099, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'wlp6s0'}
2024-12-10 18:33:33,253 DEBUG probert.network:672 link_change CHANGE {'ifindex': 4, 'flags': 4099, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'wlp6s0'}
2024-12-10 18:33:33,253 DEBUG root:38 start: subiquity/Network/_send_update: CHANGE wlp6s0
2024-12-10 18:33:33,253 DEBUG root:38 finish: subiquity/Network/_send_update: SUCCESS: CHANGE wlp6s0
2024-12-10 18:33:33,256 INFO root:38 start: subiquity/Meta/client_variant_POST: 
2024-12-10 18:33:33,257 INFO root:38 finish: subiquity/Meta/client_variant_POST: SUCCESS: 200 null
2024-12-10 18:33:33,257 DEBUG subiquitycore.utils:133 arun_command ['setupcon', '--save', '--force', '--keyboard-only'] exited with code 0
2024-12-10 18:33:33,258 DEBUG subiquitycore.utils:119 arun_command called: ['/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin/subiquity-loadkeys']
2024-12-10 18:33:33,262 DEBUG subiquitycore.utils:133 arun_command ['/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin/subiquity-loadkeys'] exited with code 0
2024-12-10 18:33:33,262 DEBUG root:38 finish: subiquity/Keyboard/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:33,262 DEBUG subiquity.models.subiquity:256 model keyboard for install stage is configured, to go {'source', 'network', 'filesystem'}
2024-12-10 18:33:33,262 DEBUG root:38 start: subiquity/Zdev/apply_autoinstall_config: 
2024-12-10 18:33:33,263 DEBUG root:38 finish: subiquity/Zdev/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:33,263 DEBUG root:38 start: subiquity/Source/apply_autoinstall_config: 
2024-12-10 18:33:33,263 DEBUG root:38 finish: subiquity/Source/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:33,263 DEBUG subiquity.models.subiquity:256 model source for install stage is configured, to go {'network', 'filesystem'}
2024-12-10 18:33:33,264 DEBUG subiquity.server.controllers.drivers:89 source variant has been set. Querying list of drivers.
2024-12-10 18:33:33,264 DEBUG root:38 start: subiquity/Network/apply_autoinstall_config: 
2024-12-10 18:33:33,264 DEBUG subiquitycore.utils:119 arun_command called: ('nmcli', 'networking')
2024-12-10 18:33:33,281 DEBUG subiquity.server.controllers.filesystem:421 got system None for variation minimal
2024-12-10 18:33:33,281 DEBUG curtin:117 Setting up Layered Source for stack ['///cdrom/casper/minimal.squashfs', '///cdrom/casper/minimal.enhanced-secureboot.squashfs', '///cdrom/casper/minimal.enhanced-secureboot.de.squashfs']
2024-12-10 18:33:33,295 DEBUG curtin:120 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.squashfs', '/tmp/tmp0j5gu92p/minimal.squashfs.dir'] with allowed return codes [0] (capture=True)
2024-12-10 18:33:33,314 DEBUG curtin:120 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.enhanced-secureboot.squashfs', '/tmp/tmp0j5gu92p/minimal.enhanced-secureboot.squashfs.dir'] with allowed return codes [0] (capture=True)
2024-12-10 18:33:33,396 DEBUG curtin:120 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.enhanced-secureboot.de.squashfs', '/tmp/tmp0j5gu92p/minimal.enhanced-secureboot.de.squashfs.dir'] with allowed return codes [0] (capture=True)
2024-12-10 18:33:33,451 DEBUG curtin:120 Running command ['mount', '-o', 'lowerdir=/tmp/tmp0j5gu92p/minimal.enhanced-secureboot.de.squashfs.dir:/tmp/tmp0j5gu92p/minimal.enhanced-secureboot.squashfs.dir:/tmp/tmp0j5gu92p/minimal.squashfs.dir', '-t', 'overlay', 'overlay', '/tmp/tmp0j5gu92p/root.dir'] with allowed return codes [0] (capture=True)
2024-12-10 18:33:33,464 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', 'mount', '-o', 'bind', '/tmp/tmp0j5gu92p/root.dir/var/lib/snapd/seed/systems', '/var/lib/snapd/seed/systems']
2024-12-10 18:33:33,493 DEBUG root:38 start: subiquity/Drivers/_list_drivers: 
2024-12-10 18:33:33,493 DEBUG root:38 start: subiquity/Drivers/_list_drivers/wait_apt: 
2024-12-10 18:33:33,495 INFO root:38 start: subiquity/Meta/status_GET: 
2024-12-10 18:33:33,495 INFO root:38 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "nonreportable_erro...
2024-12-10 18:33:33,496 DEBUG subiquitycore.utils:133 arun_command ('nmcli', 'networking') exited with code 0
2024-12-10 18:33:33,496 DEBUG subiquity.server.controllers.network:188 NetworkManager is enabled and no network autoinstall section was found. Not applying network settings.
2024-12-10 18:33:33,509 DEBUG pyroute2.ndb.125574258604640.sources.localhost:74 init
2024-12-10 18:33:33,510 DEBUG pyroute2.ndb.125574258604640.sources.localhost:384 starting the source
2024-12-10 18:33:33,510 DEBUG pyroute2.ndb.125574258604640.sources.localhost/nsmanager:74 init
2024-12-10 18:33:33,511 DEBUG pyroute2.ndb.125574258604640.sources.localhost:74 connecting
2024-12-10 18:33:33,512 INFO probert.lvm:120 b'  1 logical volume(s) in volume group "ubuntu-vg" now active\n'
2024-12-10 18:33:33,513 DEBUG pyroute2.ndb.125574258604640.sources.localhost:74 loading
2024-12-10 18:33:33,516 DEBUG pyroute2.ndb.125574258604640.sources.localhost/nsmanager:384 starting the source
2024-12-10 18:33:33,519 DEBUG pyroute2.ndb.125574258604640.sources.localhost/nsmanager:74 connecting
2024-12-10 18:33:33,521 DEBUG pyroute2.ndb.125574258604640.sources.localhost/nsmanager:74 loading
2024-12-10 18:33:33,535 DEBUG pyroute2.ndb.125574258604640.sources.localhost:74 running
2024-12-10 18:33:33,536 DEBUG pyroute2.ndb.125574258604640.sources.localhost/nsmanager:74 running
2024-12-10 18:33:33,537 DEBUG pyroute2.ndb.125574258604640.sources.localhost:399 source shutdown
2024-12-10 18:33:33,538 DEBUG pyroute2.ndb.125574258604640.sources.localhost:374 sync
2024-12-10 18:33:33,538 DEBUG pyroute2.ndb.125574258604640.sources.localhost:369 shutdown handled by the main thread
2024-12-10 18:33:33,538 DEBUG pyroute2.ndb.125574258604640.sources.localhost:74 stopped
2024-12-10 18:33:33,538 DEBUG pyroute2.ndb.125574258604640.main:851 flush DB for the target localhost
2024-12-10 18:33:33,538 DEBUG pyroute2.ndb.125574258604640.sources.localhost/nsmanager:399 source shutdown
2024-12-10 18:33:33,542 DEBUG pyroute2.ndb.125574258604640.sources.localhost/nsmanager:374 sync
2024-12-10 18:33:33,542 DEBUG pyroute2.ndb.125574258604640.sources.localhost/nsmanager:369 shutdown handled by the main thread
2024-12-10 18:33:33,542 DEBUG pyroute2.ndb.125574258604640.sources.localhost/nsmanager:74 stopped
2024-12-10 18:33:33,542 DEBUG pyroute2.ndb.125574258604640.main:851 flush DB for the target localhost/nsmanager
2024-12-10 18:33:33,543 DEBUG subiquitycore.controllers.network:103 default routes True
2024-12-10 18:33:33,543 DEBUG subiquitycore.models.network:469 has_network True
2024-12-10 18:33:33,543 DEBUG root:38 finish: subiquity/Network/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:33,544 DEBUG subiquitycore.models.network:469 has_network True
2024-12-10 18:33:33,544 DEBUG subiquity.models.subiquity:256 model network for install stage is configured, to go {'filesystem'}
2024-12-10 18:33:33,544 DEBUG subiquity.models.subiquity:256 model network for postinstall stage is configured, to go {'ubuntu_pro', 'ssh', 'snaplist', 'drivers', 'codecs', 'timezone', 'identity', 'active_directory'}
2024-12-10 18:33:33,544 DEBUG root:38 start: subiquity/UbuntuPro/apply_autoinstall_config: 
2024-12-10 18:33:33,544 DEBUG root:38 finish: subiquity/UbuntuPro/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:33,544 DEBUG subiquity.models.subiquity:256 model ubuntu_pro for postinstall stage is configured, to go {'ssh', 'snaplist', 'drivers', 'codecs', 'timezone', 'identity', 'active_directory'}
2024-12-10 18:33:33,544 DEBUG root:38 start: subiquity/Proxy/apply_autoinstall_config: 
2024-12-10 18:33:33,545 DEBUG root:38 finish: subiquity/Proxy/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:33,545 DEBUG root:38 start: subiquity/Mirror/apply_autoinstall_config: 
2024-12-10 18:33:33,545 DEBUG root:38 start: subiquity/Mirror/apply_autoinstall_config/waiting: 
2024-12-10 18:33:33,549 INFO root:38 start: subiquity/Meta/status_GET: 
2024-12-10 18:33:33,553 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', 'mount', '-o', 'bind', '/tmp/tmp0j5gu92p/root.dir/var/lib/snapd/seed/snaps/pc-kernel_1938.snap', '/var/lib/snapd/seed/snaps/pc-kernel_1938.snap']
2024-12-10 18:33:33,577 DEBUG root:38 finish: subiquity/Mirror/apply_autoinstall_config/waiting: SUCCESS: 
2024-12-10 18:33:33,577 DEBUG subiquity.server.controllers.mirror:202 Iterating over country-mirror
2024-12-10 18:33:33,578 DEBUG curtin:117 Setting up Layered Source for stack ['///cdrom/casper/minimal.squashfs', '///cdrom/casper/minimal.de.squashfs']
2024-12-10 18:33:33,578 DEBUG curtin:120 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.squashfs', '/tmp/tmphtby08yy/minimal.squashfs.dir'] with allowed return codes [0] (capture=True)
2024-12-10 18:33:33,583 DEBUG curtin:120 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.de.squashfs', '/tmp/tmphtby08yy/minimal.de.squashfs.dir'] with allowed return codes [0] (capture=True)
2024-12-10 18:33:33,636 DEBUG curtin:120 Running command ['mount', '-o', 'lowerdir=/tmp/tmphtby08yy/minimal.de.squashfs.dir:/tmp/tmphtby08yy/minimal.squashfs.dir', '-t', 'overlay', 'overlay', '/tmp/tmphtby08yy/root.dir'] with allowed return codes [0] (capture=True)
2024-12-10 18:33:33,639 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', 'mount', '-o', 'lowerdir=/tmp/tmphtby08yy/root.dir,upperdir=/tmp/tmpixrsqm6r/upper,workdir=/tmp/tmpixrsqm6r/work', '-t', 'overlay', 'overlay', '/tmp/tmpixrsqm6r/mount']
2024-12-10 18:33:33,642 DEBUG subiquitycore.utils:133 arun_command ['/usr/sbin/realm', 'discover'] exited with code 1
2024-12-10 18:33:33,691 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', 'mount', '-o', 'bind', '/tmp/tmp0j5gu92p/root.dir/var/lib/snapd/seed/snaps/pc_184.snap', '/var/lib/snapd/seed/snaps/pc_184.snap']
2024-12-10 18:33:33,703 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', '/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3627.1"}}', '-c', '/var/log/installer/curtin-install/subiquity-curtin-apt.conf', 'apt-config', '-t', '/tmp/tmpixrsqm6r/mount']
2024-12-10 18:33:34,013 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2024-12-10 18:33:34,014 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2024-12-10 18:33:34,015 DEBUG probert.dasd:134 Probing DASD devies
2024-12-10 18:33:34,015 DEBUG probert.dasd:137 DASD devices only present on s390x, arch=x86_64
2024-12-10 18:33:34,125 DEBUG probert.utils:85 Command `/usr/sbin/dumpe2fs -h /dev/nvme0n1p2` exited with result: 0
2024-12-10 18:33:34,125 DEBUG probert.utils:48 stdout: ------------------------------------------
2024-12-10 18:33:34,125 DEBUG probert.utils:50 Filesystem volume name:   <none>
2024-12-10 18:33:34,125 DEBUG probert.utils:50 Last mounted on:          /target/boot
2024-12-10 18:33:34,125 DEBUG probert.utils:50 Filesystem UUID:          3612e495-80de-4c59-830e-51a281320cb6
2024-12-10 18:33:34,125 DEBUG probert.utils:50 Filesystem magic number:  0xEF53
2024-12-10 18:33:34,125 DEBUG probert.utils:50 Filesystem revision #:    1 (dynamic)
2024-12-10 18:33:34,125 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
2024-12-10 18:33:34,125 DEBUG probert.utils:50 Filesystem flags:         signed_directory_hash 
2024-12-10 18:33:34,125 DEBUG probert.utils:50 Default mount options:    user_xattr acl
2024-12-10 18:33:34,125 DEBUG probert.utils:50 Filesystem state:         clean
2024-12-10 18:33:34,125 DEBUG probert.utils:50 Errors behavior:          Continue
2024-12-10 18:33:34,125 DEBUG probert.utils:50 Filesystem OS type:       Linux
2024-12-10 18:33:34,125 DEBUG probert.utils:50 Inode count:              131072
2024-12-10 18:33:34,125 DEBUG probert.utils:50 Block count:              524288
2024-12-10 18:33:34,125 DEBUG probert.utils:50 Reserved block count:     26214
2024-12-10 18:33:34,125 DEBUG probert.utils:50 Overhead clusters:        26150
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Free blocks:              498132
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Free inodes:              131061
2024-12-10 18:33:34,126 DEBUG probert.utils:50 First block:              0
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Block size:               4096
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Fragment size:            4096
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Group descriptor size:    64
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Reserved GDT blocks:      255
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Blocks per group:         32768
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Fragments per group:      32768
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Inodes per group:         8192
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Inode blocks per group:   512
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Flex block group size:    16
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Filesystem created:       Tue Dec 10 19:27:17 2024
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Last mount time:          Tue Dec 10 19:27:26 2024
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Last write time:          Tue Dec 10 19:27:26 2024
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Mount count:              1
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Maximum mount count:      -1
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Last checked:             Tue Dec 10 19:27:17 2024
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Check interval:           0 (<none>)
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Lifetime writes:          1045 kB
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Reserved blocks uid:      0 (user root)
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Reserved blocks gid:      0 (group root)
2024-12-10 18:33:34,126 DEBUG probert.utils:50 First inode:              11
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Inode size:	          256
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Required extra isize:     32
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Desired extra isize:      32
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Journal inode:            8
2024-12-10 18:33:34,126 DEBUG probert.utils:50 Default directory hash:   half_md4
2024-12-10 18:33:34,127 DEBUG probert.utils:50 Directory Hash Seed:      dd31ca4d-d7cc-4b4e-abd7-fdc50c9e40a0
2024-12-10 18:33:34,127 DEBUG probert.utils:50 Journal backup:           inode blocks
2024-12-10 18:33:34,127 DEBUG probert.utils:50 Checksum type:            crc32c
2024-12-10 18:33:34,127 DEBUG probert.utils:50 Checksum:                 0x6b9f26be
2024-12-10 18:33:34,127 DEBUG probert.utils:50 Journal features:         journal_64bit journal_checksum_v3
2024-12-10 18:33:34,127 DEBUG probert.utils:50 Total journal size:       64M
2024-12-10 18:33:34,127 DEBUG probert.utils:50 Total journal blocks:     16384
2024-12-10 18:33:34,127 DEBUG probert.utils:50 Max transaction length:   16384
2024-12-10 18:33:34,127 DEBUG probert.utils:50 Fast commit length:       0
2024-12-10 18:33:34,127 DEBUG probert.utils:50 Journal sequence:         0x00000002
2024-12-10 18:33:34,127 DEBUG probert.utils:50 Journal start:            1
2024-12-10 18:33:34,127 DEBUG probert.utils:50 Journal checksum type:    crc32c
2024-12-10 18:33:34,127 DEBUG probert.utils:50 Journal checksum:         0xd7a8be7f
2024-12-10 18:33:34,127 DEBUG probert.utils:50 
2024-12-10 18:33:34,127 DEBUG probert.utils:48 stderr: ------------------------------------------
2024-12-10 18:33:34,127 DEBUG probert.utils:50 dumpe2fs 1.47.0 (5-Feb-2023)
2024-12-10 18:33:34,127 DEBUG probert.utils:88 --------------------------------------------------
2024-12-10 18:33:34,144 DEBUG probert.utils:85 Command `/usr/sbin/dumpe2fs -h /dev/dm-0` exited with result: 0
2024-12-10 18:33:34,144 DEBUG probert.utils:48 stdout: ------------------------------------------
2024-12-10 18:33:34,144 DEBUG probert.utils:50 Filesystem volume name:   <none>
2024-12-10 18:33:34,144 DEBUG probert.utils:50 Last mounted on:          /target
2024-12-10 18:33:34,144 DEBUG probert.utils:50 Filesystem UUID:          c630c14d-6fba-43d5-bf95-1faedafc4000
2024-12-10 18:33:34,144 DEBUG probert.utils:50 Filesystem magic number:  0xEF53
2024-12-10 18:33:34,144 DEBUG probert.utils:50 Filesystem revision #:    1 (dynamic)
2024-12-10 18:33:34,144 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
2024-12-10 18:33:34,144 DEBUG probert.utils:50 Filesystem flags:         signed_directory_hash 
2024-12-10 18:33:34,144 DEBUG probert.utils:50 Default mount options:    user_xattr acl
2024-12-10 18:33:34,144 DEBUG probert.utils:50 Filesystem state:         clean
2024-12-10 18:33:34,144 DEBUG probert.utils:50 Errors behavior:          Continue
2024-12-10 18:33:34,144 DEBUG probert.utils:50 Filesystem OS type:       Linux
2024-12-10 18:33:34,144 DEBUG probert.utils:50 Inode count:              6553600
2024-12-10 18:33:34,144 DEBUG probert.utils:50 Block count:              26214400
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Reserved block count:     1310720
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Overhead clusters:        557842
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Free blocks:              25656552
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Free inodes:              6553589
2024-12-10 18:33:34,145 DEBUG probert.utils:50 First block:              0
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Block size:               4096
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Fragment size:            4096
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Group descriptor size:    64
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Reserved GDT blocks:      1024
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Blocks per group:         32768
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Fragments per group:      32768
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Inodes per group:         8192
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Inode blocks per group:   512
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Flex block group size:    16
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Filesystem created:       Tue Dec 10 19:27:19 2024
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Last mount time:          Tue Dec 10 19:27:26 2024
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Last write time:          Tue Dec 10 19:27:26 2024
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Mount count:              1
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Maximum mount count:      -1
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Last checked:             Tue Dec 10 19:27:19 2024
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Check interval:           0 (<none>)
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Lifetime writes:          4332 kB
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Reserved blocks uid:      0 (user root)
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Reserved blocks gid:      0 (group root)
2024-12-10 18:33:34,145 DEBUG probert.utils:50 First inode:              11
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Inode size:	          256
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Required extra isize:     32
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Desired extra isize:      32
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Journal inode:            8
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Default directory hash:   half_md4
2024-12-10 18:33:34,145 DEBUG probert.utils:50 Directory Hash Seed:      acae8209-9b6d-453c-b1dd-f73f13262608
2024-12-10 18:33:34,146 DEBUG probert.utils:50 Journal backup:           inode blocks
2024-12-10 18:33:34,146 DEBUG probert.utils:50 Checksum type:            crc32c
2024-12-10 18:33:34,146 DEBUG probert.utils:50 Checksum:                 0x1a189414
2024-12-10 18:33:34,146 DEBUG probert.utils:50 Journal features:         journal_64bit journal_checksum_v3
2024-12-10 18:33:34,146 DEBUG probert.utils:50 Total journal size:       512M
2024-12-10 18:33:34,146 DEBUG probert.utils:50 Total journal blocks:     131072
2024-12-10 18:33:34,146 DEBUG probert.utils:50 Max transaction length:   131072
2024-12-10 18:33:34,146 DEBUG probert.utils:50 Fast commit length:       0
2024-12-10 18:33:34,146 DEBUG probert.utils:50 Journal sequence:         0x00000002
2024-12-10 18:33:34,146 DEBUG probert.utils:50 Journal start:            1
2024-12-10 18:33:34,146 DEBUG probert.utils:50 Journal checksum type:    crc32c
2024-12-10 18:33:34,146 DEBUG probert.utils:50 Journal checksum:         0x12c13c9a
2024-12-10 18:33:34,146 DEBUG probert.utils:50 
2024-12-10 18:33:34,146 DEBUG probert.utils:48 stderr: ------------------------------------------
2024-12-10 18:33:34,146 DEBUG probert.utils:50 dumpe2fs 1.47.0 (5-Feb-2023)
2024-12-10 18:33:34,146 DEBUG probert.utils:88 --------------------------------------------------
2024-12-10 18:33:34,149 DEBUG probert.utils:85 Command `/usr/sbin/resize2fs -P /dev/nvme0n1p2` exited with result: 1
2024-12-10 18:33:34,149 DEBUG probert.utils:52 <empty stdout>
2024-12-10 18:33:34,149 DEBUG probert.utils:48 stderr: ------------------------------------------
2024-12-10 18:33:34,149 DEBUG probert.utils:50 resize2fs 1.47.0 (5-Feb-2023)
2024-12-10 18:33:34,149 DEBUG probert.utils:50 Please run 'e2fsck -f /dev/nvme0n1p2' first.
2024-12-10 18:33:34,149 DEBUG probert.utils:50 
2024-12-10 18:33:34,149 DEBUG probert.utils:88 --------------------------------------------------
2024-12-10 18:33:34,151 DEBUG probert.utils:85 Command `/usr/sbin/resize2fs -P /dev/dm-0` exited with result: 1
2024-12-10 18:33:34,151 DEBUG probert.utils:52 <empty stdout>
2024-12-10 18:33:34,151 DEBUG probert.utils:48 stderr: ------------------------------------------
2024-12-10 18:33:34,151 DEBUG probert.utils:50 resize2fs 1.47.0 (5-Feb-2023)
2024-12-10 18:33:34,151 DEBUG probert.utils:50 Please run 'e2fsck -f /dev/dm-0' first.
2024-12-10 18:33:34,151 DEBUG probert.utils:50 
2024-12-10 18:33:34,151 DEBUG probert.utils:88 --------------------------------------------------
2024-12-10 18:33:34,733 DEBUG root:38 start: subiquity/Mirror/cmd-apt-config: curtin command apt-config
2024-12-10 18:33:34,916 DEBUG curtin:1369 Extracting storage config from probe data
2024-12-10 18:33:34,917 DEBUG curtin:73 /dev/nvme0n1 is multipath device member? False
2024-12-10 18:33:34,917 DEBUG curtin:86 /dev/nvme0n1 is multipath device partition? False
2024-12-10 18:33:34,917 DEBUG curtin:61 /dev/nvme0n1 is multipath device? False
2024-12-10 18:33:34,917 DEBUG curtin:86 /dev/nvme0n1 is multipath device partition? False
2024-12-10 18:33:34,917 DEBUG curtin:61 /dev/nvme0n1 is multipath device? False
2024-12-10 18:33:34,928 DEBUG curtin:73 /dev/nvme0n1p1 is multipath device member? False
2024-12-10 18:33:34,928 DEBUG curtin:86 /dev/nvme0n1p1 is multipath device partition? False
2024-12-10 18:33:34,928 DEBUG curtin:61 /dev/nvme0n1p1 is multipath device? False
2024-12-10 18:33:34,928 DEBUG curtin:86 /dev/nvme0n1p1 is multipath device partition? False
2024-12-10 18:33:34,928 DEBUG curtin:86 /dev/nvme0n1p1 is multipath device partition? False
2024-12-10 18:33:34,937 DEBUG curtin:73 /dev/nvme0n1p2 is multipath device member? False
2024-12-10 18:33:34,937 DEBUG curtin:86 /dev/nvme0n1p2 is multipath device partition? False
2024-12-10 18:33:34,938 DEBUG curtin:61 /dev/nvme0n1p2 is multipath device? False
2024-12-10 18:33:34,938 DEBUG curtin:86 /dev/nvme0n1p2 is multipath device partition? False
2024-12-10 18:33:34,938 DEBUG curtin:86 /dev/nvme0n1p2 is multipath device partition? False
2024-12-10 18:33:34,947 DEBUG curtin:73 /dev/nvme0n1p3 is multipath device member? False
2024-12-10 18:33:34,947 DEBUG curtin:86 /dev/nvme0n1p3 is multipath device partition? False
2024-12-10 18:33:34,947 DEBUG curtin:61 /dev/nvme0n1p3 is multipath device? False
2024-12-10 18:33:34,947 DEBUG curtin:86 /dev/nvme0n1p3 is multipath device partition? False
2024-12-10 18:33:34,947 DEBUG curtin:86 /dev/nvme0n1p3 is multipath device partition? False
2024-12-10 18:33:34,957 DEBUG curtin:61 /dev/dm-0 is multipath device? False
2024-12-10 18:33:34,957 DEBUG curtin:86 /dev/dm-0 is multipath device partition? False
2024-12-10 18:33:34,957 DEBUG curtin:73 /dev/nvme0n1p1 is multipath device member? False
2024-12-10 18:33:34,969 DEBUG curtin:73 /dev/nvme0n1p2 is multipath device member? False
2024-12-10 18:33:34,981 DEBUG curtin:73 /dev/dm-0 is multipath device member? False
2024-12-10 18:33:35,023 DEBUG curtin:1376 Sorting extracted configurations
2024-12-10 18:33:35,023 INFO curtin:1396 Validating extracted storage config components
2024-12-10 18:33:35,040 DEBUG curtin:1413 Extracted (unmerged) storage config:
storage:
-   id: nvme-controller-nvme0
    transport: pcie
    type: nvme_controller
-   id: disk-nvme0n1
    nvme_controller: nvme-controller-nvme0
    path: /dev/nvme0n1
    ptable: gpt
    serial: CT500P3PSSD8_2413E8A30B3D_1
    type: disk
    wwn: eui.6479a78ea0000176
-   device: disk-nvme0n1
    flag: boot
    id: partition-nvme0n1p1
    number: 1
    offset: 1048576
    partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b
    path: /dev/nvme0n1p1
    size: 1127219200
    type: partition
    uuid: a1d26e37-d5cd-4bc6-9365-a27c8668b746
-   device: disk-nvme0n1
    flag: linux
    id: partition-nvme0n1p2
    number: 2
    offset: 1128267776
    partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4
    path: /dev/nvme0n1p2
    size: 2147483648
    type: partition
    uuid: c1d978a1-ea17-4f2b-a2ea-8b9a3b99e875
-   device: disk-nvme0n1
    flag: linux
    id: partition-nvme0n1p3
    number: 3
    offset: 3275751424
    partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4
    path: /dev/nvme0n1p3
    size: 496831037440
    type: partition
    uuid: 76e662a5-830f-442f-bf21-38e743011113
-   fstype: vfat
    id: format-partition-nvme0n1p1
    type: format
    volume: partition-nvme0n1p1
-   fstype: ext4
    id: format-partition-nvme0n1p2
    type: format
    uuid: 3612e495-80de-4c59-830e-51a281320cb6
    volume: partition-nvme0n1p2
-   fstype: ext4
    id: format-lvm-partition-ubuntu-lv
    type: format
    uuid: c630c14d-6fba-43d5-bf95-1faedafc4000
    volume: lvm-partition-ubuntu-lv
-   devices:
    - partition-nvme0n1p3
    id: lvm-volgroup-ubuntu-vg
    name: ubuntu-vg
    type: lvm_volgroup
-   id: lvm-partition-ubuntu-lv
    name: ubuntu-lv
    path: /dev/dm-0
    size: 107374182400B
    type: lvm_partition
    volgroup: lvm-volgroup-ubuntu-vg

2024-12-10 18:33:35,040 DEBUG curtin:1417 Generating storage config dependencies
2024-12-10 18:33:35,040 DEBUG curtin:243 Validate: disk-nvme0n1:SourceType:disk -> (DepId:nvme-controller-nvme0 DepType:nvme_controller) in SourceDeps:{'dasd', 'nvme_controller'} ? result=True
2024-12-10 18:33:35,040 DEBUG curtin:243 Validate: partition-nvme0n1p1:SourceType:partition -> (DepId:disk-nvme0n1 DepType:disk) in SourceDeps:{'bcache', 'partition', 'disk', 'raid'} ? result=True
2024-12-10 18:33:35,040 DEBUG curtin:243 Validate: disk-nvme0n1:SourceType:disk -> (DepId:nvme-controller-nvme0 DepType:nvme_controller) in SourceDeps:{'dasd', 'nvme_controller'} ? result=True
2024-12-10 18:33:35,040 DEBUG curtin:243 Validate: partition-nvme0n1p2:SourceType:partition -> (DepId:disk-nvme0n1 DepType:disk) in SourceDeps:{'bcache', 'partition', 'disk', 'raid'} ? result=True
2024-12-10 18:33:35,040 DEBUG curtin:243 Validate: disk-nvme0n1:SourceType:disk -> (DepId:nvme-controller-nvme0 DepType:nvme_controller) in SourceDeps:{'dasd', 'nvme_controller'} ? result=True
2024-12-10 18:33:35,040 DEBUG curtin:243 Validate: partition-nvme0n1p3:SourceType:partition -> (DepId:disk-nvme0n1 DepType:disk) in SourceDeps:{'bcache', 'partition', 'disk', 'raid'} ? result=True
2024-12-10 18:33:35,040 DEBUG curtin:243 Validate: disk-nvme0n1:SourceType:disk -> (DepId:nvme-controller-nvme0 DepType:nvme_controller) in SourceDeps:{'dasd', 'nvme_controller'} ? result=True
2024-12-10 18:33:35,040 DEBUG curtin:243 Validate: format-partition-nvme0n1p1:SourceType:format -> (DepId:partition-nvme0n1p1 DepType:partition) in SourceDeps:{'dm_crypt', 'bcache', 'partition', 'lvm_partition', 'disk', 'raid'} ? result=True
2024-12-10 18:33:35,040 DEBUG curtin:243 Validate: partition-nvme0n1p1:SourceType:partition -> (DepId:disk-nvme0n1 DepType:disk) in SourceDeps:{'bcache', 'partition', 'disk', 'raid'} ? result=True
2024-12-10 18:33:35,041 DEBUG curtin:243 Validate: disk-nvme0n1:SourceType:disk -> (DepId:nvme-controller-nvme0 DepType:nvme_controller) in SourceDeps:{'dasd', 'nvme_controller'} ? result=True
2024-12-10 18:33:35,041 DEBUG curtin:243 Validate: format-partition-nvme0n1p2:SourceType:format -> (DepId:partition-nvme0n1p2 DepType:partition) in SourceDeps:{'dm_crypt', 'bcache', 'partition', 'lvm_partition', 'disk', 'raid'} ? result=True
2024-12-10 18:33:35,041 DEBUG curtin:243 Validate: partition-nvme0n1p2:SourceType:partition -> (DepId:disk-nvme0n1 DepType:disk) in SourceDeps:{'bcache', 'partition', 'disk', 'raid'} ? result=True
2024-12-10 18:33:35,041 DEBUG curtin:243 Validate: disk-nvme0n1:SourceType:disk -> (DepId:nvme-controller-nvme0 DepType:nvme_controller) in SourceDeps:{'dasd', 'nvme_controller'} ? result=True
2024-12-10 18:33:35,041 DEBUG curtin:243 Validate: format-lvm-partition-ubuntu-lv:SourceType:format -> (DepId:lvm-partition-ubuntu-lv DepType:lvm_partition) in SourceDeps:{'dm_crypt', 'bcache', 'partition', 'lvm_partition', 'disk', 'raid'} ? result=True
2024-12-10 18:33:35,041 DEBUG curtin:243 Validate: lvm-partition-ubuntu-lv:SourceType:lvm_partition -> (DepId:lvm-volgroup-ubuntu-vg DepType:lvm_volgroup) in SourceDeps:{'lvm_volgroup'} ? result=True
2024-12-10 18:33:35,041 DEBUG curtin:243 Validate: lvm-volgroup-ubuntu-vg:SourceType:lvm_volgroup -> (DepId:partition-nvme0n1p3 DepType:partition) in SourceDeps:{'dm_crypt', 'bcache', 'partition', 'disk', 'raid'} ? result=True
2024-12-10 18:33:35,041 DEBUG curtin:243 Validate: partition-nvme0n1p3:SourceType:partition -> (DepId:disk-nvme0n1 DepType:disk) in SourceDeps:{'bcache', 'partition', 'disk', 'raid'} ? result=True
2024-12-10 18:33:35,041 DEBUG curtin:243 Validate: disk-nvme0n1:SourceType:disk -> (DepId:nvme-controller-nvme0 DepType:nvme_controller) in SourceDeps:{'dasd', 'nvme_controller'} ? result=True
2024-12-10 18:33:35,041 DEBUG curtin:243 Validate: lvm-volgroup-ubuntu-vg:SourceType:lvm_volgroup -> (DepId:partition-nvme0n1p3 DepType:partition) in SourceDeps:{'dm_crypt', 'bcache', 'partition', 'disk', 'raid'} ? result=True
2024-12-10 18:33:35,041 DEBUG curtin:243 Validate: partition-nvme0n1p3:SourceType:partition -> (DepId:disk-nvme0n1 DepType:disk) in SourceDeps:{'bcache', 'partition', 'disk', 'raid'} ? result=True
2024-12-10 18:33:35,041 DEBUG curtin:243 Validate: disk-nvme0n1:SourceType:disk -> (DepId:nvme-controller-nvme0 DepType:nvme_controller) in SourceDeps:{'dasd', 'nvme_controller'} ? result=True
2024-12-10 18:33:35,041 DEBUG curtin:243 Validate: lvm-partition-ubuntu-lv:SourceType:lvm_partition -> (DepId:lvm-volgroup-ubuntu-vg DepType:lvm_volgroup) in SourceDeps:{'lvm_volgroup'} ? result=True
2024-12-10 18:33:35,041 DEBUG curtin:243 Validate: lvm-volgroup-ubuntu-vg:SourceType:lvm_volgroup -> (DepId:partition-nvme0n1p3 DepType:partition) in SourceDeps:{'dm_crypt', 'bcache', 'partition', 'disk', 'raid'} ? result=True
2024-12-10 18:33:35,041 DEBUG curtin:243 Validate: partition-nvme0n1p3:SourceType:partition -> (DepId:disk-nvme0n1 DepType:disk) in SourceDeps:{'bcache', 'partition', 'disk', 'raid'} ? result=True
2024-12-10 18:33:35,041 DEBUG curtin:243 Validate: disk-nvme0n1:SourceType:disk -> (DepId:nvme-controller-nvme0 DepType:nvme_controller) in SourceDeps:{'dasd', 'nvme_controller'} ? result=True
2024-12-10 18:33:35,041 DEBUG curtin:1423 Merging storage config dependencies
2024-12-10 18:33:35,044 DEBUG curtin:1428 Merged storage config:
storage:
    config:
    -   id: nvme-controller-nvme0
        transport: pcie
        type: nvme_controller
    -   id: disk-nvme0n1
        nvme_controller: nvme-controller-nvme0
        path: /dev/nvme0n1
        ptable: gpt
        serial: CT500P3PSSD8_2413E8A30B3D_1
        type: disk
        wwn: eui.6479a78ea0000176
    -   device: disk-nvme0n1
        flag: boot
        id: partition-nvme0n1p1
        number: 1
        offset: 1048576
        partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b
        path: /dev/nvme0n1p1
        size: 1127219200
        type: partition
        uuid: a1d26e37-d5cd-4bc6-9365-a27c8668b746
    -   device: disk-nvme0n1
        flag: linux
        id: partition-nvme0n1p2
        number: 2
        offset: 1128267776
        partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4
        path: /dev/nvme0n1p2
        size: 2147483648
        type: partition
        uuid: c1d978a1-ea17-4f2b-a2ea-8b9a3b99e875
    -   device: disk-nvme0n1
        flag: linux
        id: partition-nvme0n1p3
        number: 3
        offset: 3275751424
        partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4
        path: /dev/nvme0n1p3
        size: 496831037440
        type: partition
        uuid: 76e662a5-830f-442f-bf21-38e743011113
    -   fstype: vfat
        id: format-partition-nvme0n1p1
        type: format
        volume: partition-nvme0n1p1
    -   fstype: ext4
        id: format-partition-nvme0n1p2
        type: format
        uuid: 3612e495-80de-4c59-830e-51a281320cb6
        volume: partition-nvme0n1p2
    -   devices:
        - partition-nvme0n1p3
        id: lvm-volgroup-ubuntu-vg
        name: ubuntu-vg
        type: lvm_volgroup
    -   id: lvm-partition-ubuntu-lv
        name: ubuntu-lv
        path: /dev/dm-0
        size: 107374182400B
        type: lvm_partition
        volgroup: lvm-volgroup-ubuntu-vg
    -   fstype: ext4
        id: format-lvm-partition-ubuntu-lv
        type: format
        uuid: c630c14d-6fba-43d5-bf95-1faedafc4000
        volume: lvm-partition-ubuntu-lv
    version: 2

2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1532 majmin_to_dev {'259:0': Disk(ptable='gpt', serial='CT500P3PSSD8_2413E8A30B3D_1', wwn='eui.6479a78ea0000176', nvme_controller=nvme-controller-nvme0, path='/dev/nvme0n1', preserve=True, id='disk-nvme0n1', type='disk'), '259:1': Partition(device=disk-nvme0n1, size=1127219200, flag='boot', number=1, preserve=True, offset=1048576, partition_type='c12a7328-f81f-11d2-ba4b-00a0c93ec93b', path='/dev/nvme0n1p1', uuid='a1d26e37-d5cd-4bc6-9365-a27c8668b746', id='partition-nvme0n1p1', type='partition'), '259:2': Partition(device=disk-nvme0n1, size=2147483648, flag='linux', number=2, preserve=True, offset=1128267776, partition_type='0fc63daf-8483-4772-8e79-3d69d8477de4', path='/dev/nvme0n1p2', uuid='c1d978a1-ea17-4f2b-a2ea-8b9a3b99e875', id='partition-nvme0n1p2', type='partition'), '259:3': Partition(device=disk-nvme0n1, size=496831037440, flag='linux', number=3, preserve=True, offset=3275751424, partition_type='0fc63daf-8483-4772-8e79-3d69d8477de4', path='/dev/nvme0n1p3', uuid='76e662a5-830f-442f-bf21-38e743011113', id='partition-nvme0n1p3', type='partition')}
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:30
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:23
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:24
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:5
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:26
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:28
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 7:0
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:43
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 7:3
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 7:5
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 7:6
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 7:4
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 7:13
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 7:7
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 7:11
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 7:9
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 7:10
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 7:12
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 7:8
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:108
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:108
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:108
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:27
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:6
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:37
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:38
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:39
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:7
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:12
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:42
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:21
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:40
2024-12-10 18:33:35,045 DEBUG subiquity.models.filesystem:1544 considering mount of 0:25
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 0:35
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 0:41
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 0:20
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 0:36
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 0:26
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 0:73
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 7:0
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 7:15
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 0:108
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 7:14
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 7:0
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 7:16
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 0:113
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 0:117
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 0:50
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 0:4
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 0:4
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 0:4
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 0:4
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 0:4
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 0:87
2024-12-10 18:33:35,046 DEBUG subiquity.models.filesystem:1544 considering mount of 0:98
2024-12-10 18:33:35,046 DEBUG root:38 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False
2024-12-10 18:33:35,048 DEBUG subiquity.server.controllers.filesystem:1334 block probing took 1.8 seconds
2024-12-10 18:33:35,048 DEBUG subiquity.server.controllers.filesystem:1538 start_monitor
2024-12-10 18:33:35,049 DEBUG root:38 finish: subiquity/Filesystem/_probe: SUCCESS: 
2024-12-10 18:33:35,234 DEBUG subiquity.server.curtin:130 waited 0.1 seconds for events to drain
2024-12-10 18:33:35,282 DEBUG root:38 finish: subiquity/Mirror/cmd-apt-config: SUCCESS: curtin command apt-config
2024-12-10 18:33:35,335 DEBUG subiquity.server.curtin:130 waited 0.2 seconds for events to drain
2024-12-10 18:33:35,336 DEBUG subiquitycore.utils:152 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']
2024-12-10 18:33:39,004 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:33:39,008 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:33:39,008 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 4}
2024-12-10 18:33:39,982 DEBUG subiquity.server.controllers.mirror:176 Mirror checking successful
2024-12-10 18:33:39,983 DEBUG subiquity.server.controllers.mirror:178 APT output follows
2024-12-10 18:33:39,983 DEBUG subiquity.server.controllers.mirror:180 OK:1 http://de.archive.ubuntu.com/ubuntu noble InRelease
2024-12-10 18:33:39,983 DEBUG subiquity.server.controllers.mirror:180 Holen:2 http://de.archive.ubuntu.com/ubuntu noble-updates InRelease [126 kB]
2024-12-10 18:33:39,983 DEBUG subiquity.server.controllers.mirror:180 Holen:3 http://de.archive.ubuntu.com/ubuntu noble-backports InRelease [126 kB]
2024-12-10 18:33:39,983 DEBUG subiquity.server.controllers.mirror:180 Es wurden 252 kB in 1 s geholt (405 kB/s).
2024-12-10 18:33:39,983 DEBUG subiquity.server.controllers.mirror:180 Paketlisten werden gelesen…
2024-12-10 18:33:39,983 DEBUG root:38 finish: subiquity/Mirror/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:39,984 DEBUG root:38 start: subiquity/Filesystem/apply_autoinstall_config: 
2024-12-10 18:33:40,193 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/systems/enhanced-secureboot-desktop HTTP/1.1" 200 None
2024-12-10 18:33:40,195 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', 'umount', '/var/lib/snapd/seed/snaps/pc_184.snap']
2024-12-10 18:33:40,231 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', 'umount', '/var/lib/snapd/seed/snaps/pc-kernel_1938.snap']
2024-12-10 18:33:40,261 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', 'umount', '/var/lib/snapd/seed/systems']
2024-12-10 18:33:40,285 DEBUG curtin:120 Running command ['umount', '/tmp/tmp0j5gu92p/root.dir'] with allowed return codes [0] (capture=True)
2024-12-10 18:33:40,289 DEBUG curtin:120 Running command ['umount', '/tmp/tmp0j5gu92p/minimal.enhanced-secureboot.de.squashfs.dir'] with allowed return codes [0] (capture=True)
2024-12-10 18:33:40,292 DEBUG curtin:120 Running command ['umount', '/tmp/tmp0j5gu92p/minimal.enhanced-secureboot.squashfs.dir'] with allowed return codes [0] (capture=True)
2024-12-10 18:33:40,330 DEBUG curtin:120 Running command ['umount', '/tmp/tmp0j5gu92p/minimal.squashfs.dir'] with allowed return codes [0] (capture=True)
2024-12-10 18:33:40,334 DEBUG subiquity.server.controllers.filesystem:351 got system SystemDetails(current=False, volumes={'pc': Volume(schema='gpt', bootloader='grub', id='', structure=[VolumeStructure(name='EFI System partition', label='ubuntu-seed', offset=1048576, offset_write=None, size=786432000, type='C12A7328-F81F-11D2-BA4B-00A0C93EC93B', role='system-seed-null', id='', filesystem='vfat', content=[VolumeContent(source='grubx64.efi', target='EFI/boot/grubx64.efi', image='', offset=None, offset_write=None, size=0, unpack=False), VolumeContent(source='shim.efi.signed', target='EFI/boot/bootx64.efi', image='', offset=None, offset_write=None, size=0, unpack=False)], update=VolumeUpdate(edition=2, preserve=None)), VolumeStructure(name='ubuntu-boot', label='ubuntu-boot', offset=787480576, offset_write=None, size=786432000, type='0FC63DAF-8483-4772-8E79-3D69D8477DE4', role='system-boot', id='', filesystem='ext4', content=[VolumeContent(source='grubx64.efi', target='EFI/boot/grubx64.efi', image='', offset=None, offset_write=None, size=0, unpack=False)], update=VolumeUpdate(edition=1, preserve=None)), VolumeStructure(name='ubuntu-save', label='ubuntu-save', offset=1573912576, offset_write=None, size=33554432, type='0FC63DAF-8483-4772-8E79-3D69D8477DE4', role='system-save', id='', filesystem='ext4', content=None, update=VolumeUpdate(edition=0, preserve=None)), VolumeStructure(name='ubuntu-data', label='ubuntu-data', offset=1607467008, offset_write=None, size=4294967296, type='0FC63DAF-8483-4772-8E79-3D69D8477DE4', role='system-data', id='', filesystem='ext4', content=None, update=VolumeUpdate(edition=0, preserve=None))])}, storage_encryption=StorageEncryption(support=<StorageEncryptionSupport.UNAVAILABLE: 'unavailable'>, storage_safety=<StorageSafety.PREFER_ENCRYPTED: 'prefer-encrypted'>, encryption_type=<EncryptionType.NONE: ''>, unavailable_reason='not encrypting device storage as checking TPM gave: secure boot is disabled'))
2024-12-10 18:33:40,335 DEBUG subiquity.server.controllers.filesystem:421 got system SystemDetails(current=False, volumes={'pc': Volume(schema='gpt', bootloader='grub', id='', structure=[VolumeStructure(name='EFI System partition', label='ubuntu-seed', offset=1048576, offset_write=None, size=786432000, type='C12A7328-F81F-11D2-BA4B-00A0C93EC93B', role='system-seed-null', id='', filesystem='vfat', content=[VolumeContent(source='grubx64.efi', target='EFI/boot/grubx64.efi', image='', offset=None, offset_write=None, size=0, unpack=False), VolumeContent(source='shim.efi.signed', target='EFI/boot/bootx64.efi', image='', offset=None, offset_write=None, size=0, unpack=False)], update=VolumeUpdate(edition=2, preserve=None)), VolumeStructure(name='ubuntu-boot', label='ubuntu-boot', offset=787480576, offset_write=None, size=786432000, type='0FC63DAF-8483-4772-8E79-3D69D8477DE4', role='system-boot', id='', filesystem='ext4', content=[VolumeContent(source='grubx64.efi', target='EFI/boot/grubx64.efi', image='', offset=None, offset_write=None, size=0, unpack=False)], update=VolumeUpdate(edition=1, preserve=None)), VolumeStructure(name='ubuntu-save', label='ubuntu-save', offset=1573912576, offset_write=None, size=33554432, type='0FC63DAF-8483-4772-8E79-3D69D8477DE4', role='system-save', id='', filesystem='ext4', content=None, update=VolumeUpdate(edition=0, preserve=None)), VolumeStructure(name='ubuntu-data', label='ubuntu-data', offset=1607467008, offset_write=None, size=4294967296, type='0FC63DAF-8483-4772-8E79-3D69D8477DE4', role='system-data', id='', filesystem='ext4', content=None, update=VolumeUpdate(edition=0, preserve=None))])}, storage_encryption=StorageEncryption(support=<StorageEncryptionSupport.UNAVAILABLE: 'unavailable'>, storage_safety=<StorageSafety.PREFER_ENCRYPTED: 'prefer-encrypted'>, encryption_type=<EncryptionType.NONE: ''>, unavailable_reason='not encrypting device storage as checking TPM gave: secure boot is disabled')) for variation minimal-enhanced-secureboot
2024-12-10 18:33:40,335 DEBUG root:38 start: subiquity/Filesystem/apply_autoinstall_config/convert_autoinstall_config: 
2024-12-10 18:33:40,336 INFO subiquity.models.filesystem:1669 considering [Disk(ptable='gpt', serial='CT500P3PSSD8_2413E8A30B3D_1', wwn='eui.6479a78ea0000176', nvme_controller=nvme-controller-nvme0, path='/dev/nvme0n1', preserve=True, id='disk-nvme0n1', type='disk')] for {'size': 'largest'}
2024-12-10 18:33:40,336 INFO subiquity.models.filesystem:1676 For match {'size': 'largest'}, using the first candidate from [Disk(ptable='gpt', serial='CT500P3PSSD8_2413E8A30B3D_1', wwn='eui.6479a78ea0000176', nvme_controller=nvme-controller-nvme0, path='/dev/nvme0n1', preserve=True, id='disk-nvme0n1', type='disk')]
2024-12-10 18:33:40,336 INFO subiquity.server.controllers.filesystem:1471 autoinstall: running guided GuidedCapability.LVM install in mode reformat_disk using GuidedStorageTargetReformat(disk_id='disk-nvme0n1', allowed=[], disallowed=[])
2024-12-10 18:33:40,337 DEBUG subiquity.models.filesystem:2194 adding fat32 to Partition(device=disk-nvme0n1, size=1127219200, wipe='superblock', flag='boot', number=1, grub_device=True, offset=1048576, id='partition-0')
2024-12-10 18:33:40,337 DEBUG subiquity.models.filesystem:2194 adding ext4 to Partition(device=disk-nvme0n1, size=2147483648, wipe='superblock', number=2, grub_device=None, offset=1128267776, id='partition-1')
2024-12-10 18:33:40,337 DEBUG subiquity.server.controllers.filesystem:554 lv_size 107374182400 for SizingPolicy.SCALED
2024-12-10 18:33:40,337 DEBUG subiquity.models.filesystem:2194 adding ext4 to LVM_LogicalVolume(name='ubuntu-lv', volgroup=lvm_volgroup-0, size=107374182400, wipe='superblock', id='lvm_partition-0')
2024-12-10 18:33:40,338 DEBUG root:38 finish: subiquity/Filesystem/apply_autoinstall_config/convert_autoinstall_config: SUCCESS: 
2024-12-10 18:33:40,338 DEBUG root:38 finish: subiquity/Filesystem/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:40,338 DEBUG subiquity.models.subiquity:256 model filesystem for install stage is configured, to go set()
2024-12-10 18:33:40,338 DEBUG subiquity.server.controllers.filesystem:1551 stop_monitor
2024-12-10 18:33:40,338 DEBUG root:38 start: subiquity/Identity/apply_autoinstall_config: 
2024-12-10 18:33:40,339 DEBUG root:38 finish: subiquity/Identity/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:40,339 DEBUG subiquity.models.subiquity:256 model identity for postinstall stage is configured, to go {'ssh', 'snaplist', 'drivers', 'codecs', 'timezone', 'active_directory'}
2024-12-10 18:33:40,339 DEBUG root:38 start: subiquity/SSH/apply_autoinstall_config: 
2024-12-10 18:33:40,339 DEBUG root:38 finish: subiquity/SSH/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:40,339 DEBUG subiquity.models.subiquity:256 model ssh for postinstall stage is configured, to go {'snaplist', 'drivers', 'codecs', 'timezone', 'active_directory'}
2024-12-10 18:33:40,340 DEBUG root:38 start: subiquity/SnapList/apply_autoinstall_config: 
2024-12-10 18:33:40,340 DEBUG root:38 finish: subiquity/SnapList/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:40,340 DEBUG subiquity.models.subiquity:256 model snaplist for postinstall stage is configured, to go {'timezone', 'drivers', 'codecs', 'active_directory'}
2024-12-10 18:33:40,340 DEBUG root:38 start: subiquity/Ad/apply_autoinstall_config: 
2024-12-10 18:33:40,340 DEBUG root:38 finish: subiquity/Ad/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:40,340 DEBUG subiquity.models.subiquity:256 model active_directory for postinstall stage is configured, to go {'timezone', 'drivers', 'codecs'}
2024-12-10 18:33:40,340 DEBUG root:38 start: subiquity/Codecs/apply_autoinstall_config: 
2024-12-10 18:33:40,340 DEBUG root:38 finish: subiquity/Codecs/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:40,341 DEBUG subiquity.models.subiquity:256 model codecs for postinstall stage is configured, to go {'timezone', 'drivers'}
2024-12-10 18:33:40,341 DEBUG root:38 start: subiquity/Drivers/apply_autoinstall_config: 
2024-12-10 18:33:40,341 DEBUG root:38 finish: subiquity/Drivers/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:33:40,341 DEBUG subiquity.models.subiquity:256 model drivers for postinstall stage is configured, to go {'timezone'}
2024-12-10 18:33:40,341 DEBUG root:38 start: subiquity/OEM/apply_autoinstall_config: 
2024-12-10 18:33:40,341 DEBUG subiquity.models.subiquity:256 model ssh for postinstall stage is configured, to go {'timezone'}
2024-12-10 18:33:40,342 DEBUG subiquity.models.subiquity:256 model snaplist for postinstall stage is configured, to go {'timezone'}
2024-12-10 18:33:40,342 DEBUG root:38 finish: subiquity/OEM/load_metapackages_list/wait_confirmation: SUCCESS: 
2024-12-10 18:33:40,342 DEBUG root:38 start: subiquity/OEM/load_metapackages_list/wait_apt: 
2024-12-10 18:33:40,343 INFO root:38 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "NEEDS_CONFIRMATION", "confirming_tty": "", "error": null, "nonrepo...
2024-12-10 18:33:40,343 INFO root:38 start: subiquity/Install/install/configure_apt: configuring apt
2024-12-10 18:33:40,343 DEBUG curtin:117 Setting up Layered Source for stack ['///cdrom/casper/minimal.squashfs', '///cdrom/casper/minimal.de.squashfs']
2024-12-10 18:33:40,344 DEBUG curtin:120 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.squashfs', '/tmp/tmp9njqdia6/minimal.squashfs.dir'] with allowed return codes [0] (capture=True)
2024-12-10 18:33:40,348 DEBUG curtin:120 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.de.squashfs', '/tmp/tmp9njqdia6/minimal.de.squashfs.dir'] with allowed return codes [0] (capture=True)
2024-12-10 18:33:40,354 DEBUG curtin:120 Running command ['mount', '-o', 'lowerdir=/tmp/tmp9njqdia6/minimal.de.squashfs.dir:/tmp/tmp9njqdia6/minimal.squashfs.dir', '-t', 'overlay', 'overlay', '/tmp/tmp9njqdia6/root.dir'] with allowed return codes [0] (capture=True)
2024-12-10 18:33:40,357 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', 'mount', '-o', 'lowerdir=/tmp/tmp9njqdia6/root.dir,upperdir=/tmp/tmpvwll3yov/upper,workdir=/tmp/tmpvwll3yov/work', '-t', 'overlay', 'overlay', '/tmp/tmpvwll3yov/mount']
2024-12-10 18:33:40,360 INFO root:38 start: subiquity/Meta/status_GET: 
2024-12-10 18:33:40,360 INFO root:38 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "", "error": null, "nonreportable_erro...
2024-12-10 18:33:40,384 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', '/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3627.2"}}', '-c', '/var/log/installer/curtin-install/subiquity-curtin-apt.conf', 'apt-config', '-t', '/tmp/tmpvwll3yov/mount']
2024-12-10 18:33:40,397 INFO root:38 start: subiquity/Meta/status_GET: 
2024-12-10 18:33:40,398 INFO root:38 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "", "error": null, "nonreportable_erro...
2024-12-10 18:33:40,398 INFO root:38 start: subiquity/Meta/status_GET: 
2024-12-10 18:33:40,399 INFO root:38 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "", "error": null, "nonreportable_erro...
2024-12-10 18:33:40,400 INFO root:38 start: subiquity/Meta/status_GET: 
2024-12-10 18:33:40,401 INFO root:38 start: subiquity/Meta/interactive_sections_GET: 
2024-12-10 18:33:40,401 INFO root:38 finish: subiquity/Meta/interactive_sections_GET: SUCCESS: 200 null
2024-12-10 18:33:40,403 DEBUG root:38 start: subiquity/Locale/GET: 
2024-12-10 18:33:40,403 DEBUG root:38 finish: subiquity/Locale/GET: SUCCESS: 200 "de_DE.UTF-8"
2024-12-10 18:33:40,407 INFO root:38 start: subiquity/Meta/status_GET: 
2024-12-10 18:33:40,407 INFO root:38 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "", "error": null, "nonreportable_erro...
2024-12-10 18:33:40,454 INFO root:38 start: subiquity/Meta/status_GET: 
2024-12-10 18:33:40,454 INFO root:38 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "", "error": null, "nonreportable_erro...
2024-12-10 18:33:40,457 INFO root:38 start: subiquity/Meta/status_GET: 
2024-12-10 18:33:41,442 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', 'mount', '-o', 'lowerdir=/tmp/tmpvwll3yov/upper:/tmp/tmp9njqdia6/root.dir,upperdir=/tmp/tmp03_31koa/upper,workdir=/tmp/tmp03_31koa/work', '-t', 'overlay', 'overlay', '/tmp/tmp03_31koa/mount']
2024-12-10 18:33:41,472 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:33:41,480 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:33:41,480 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 4, 'ssids': [(b'XXX-SCHULNETZ', 'no status'), (b'Media', 'no status'), (b'Media', 'no status'), (b'', 'no status'), (b'Media', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'Media', 'no status'), (b'', 'no status'), (b'', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'BvSS-Surface', 'no status'), (b'BvSS-Mobil', 'no status'), (b'WRS-offen', 'no status'), (b'WRS-Mobil', 'no status'), (b'', 'no status'), (b'Media', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'BvSS-offen', 'no status'), (b'', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'WRS-offen', 'no status'), (b'WRS-Mobil', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'BvSS-Surface', 'no status'), (b'BvSS-offen', 'no status'), (b'XXX-SCHULNETZ', 'no status')]}
2024-12-10 18:33:41,481 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:33:41,485 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:33:41,486 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', 'mount', '-o', 'bind', '/cdrom', '/tmp/tmp03_31koa/mount/cdrom']
2024-12-10 18:33:41,514 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', '/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3627.3"}}', 'in-target', '-t', '/tmp/tmp03_31koa/mount', '--', 'apt-get', 'update']
2024-12-10 18:33:42,431 DEBUG root:38 start: subiquity/Install/install/configure_apt/cmd-in-target: curtin command in-target
2024-12-10 18:33:59,014 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:33:59,037 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:33:59,037 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 4}
2024-12-10 18:34:01,479 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:34:01,487 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:34:01,488 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 4, 'ssids': [(b'XXX-SCHULNETZ', 'no status'), (b'Media', 'no status'), (b'Media', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'Media', 'no status'), (b'', 'no status'), (b'', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'', 'no status'), (b'Media', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'WRS-offen', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'BvSS-offen', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'BvSS-Mobil', 'no status')]}
2024-12-10 18:34:01,488 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:34:01,495 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:34:10,744 DEBUG subiquity.server.curtin:130 waited 0.1 seconds for events to drain
2024-12-10 18:34:10,816 DEBUG root:38 finish: subiquity/Install/install/configure_apt/cmd-in-target: SUCCESS: curtin command in-target
2024-12-10 18:34:10,845 DEBUG subiquity.server.curtin:130 waited 0.2 seconds for events to drain
2024-12-10 18:34:10,845 INFO root:38 finish: subiquity/Install/install/configure_apt: SUCCESS: configuring apt
2024-12-10 18:34:10,846 INFO root:38 start: subiquity/Install/install/curtin_install: installing system
2024-12-10 18:34:10,847 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step: executing curtin install initial step
2024-12-10 18:34:10,848 DEBUG root:38 finish: subiquity/Drivers/_list_drivers/wait_apt: SUCCESS: 
2024-12-10 18:34:10,848 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', 'mount', '-o', 'lowerdir=/tmp/tmp03_31koa/upper:/tmp/tmpvwll3yov/upper:/tmp/tmp9njqdia6/root.dir,upperdir=/tmp/tmpan_haqv1/upper,workdir=/tmp/tmpan_haqv1/work', '-t', 'overlay', 'overlay', '/tmp/tmpan_haqv1/mount']
2024-12-10 18:34:10,849 DEBUG root:38 finish: subiquity/OEM/load_metapackages_list/wait_apt: SUCCESS: 
2024-12-10 18:34:10,850 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', 'mount', '-o', 'lowerdir=/tmp/tmp03_31koa/upper:/tmp/tmpvwll3yov/upper:/tmp/tmp9njqdia6/root.dir,upperdir=/tmp/tmp1ub3azom/upper,workdir=/tmp/tmp1ub3azom/work', '-t', 'overlay', 'overlay', '/tmp/tmp1ub3azom/mount']
2024-12-10 18:34:10,851 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', '/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3627.4"}}', '-c', '/var/log/installer/curtin-install/subiquity-initial.conf', 'install', '--set', 'json:stages=[]']
2024-12-10 18:34:10,909 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', 'chroot', '/tmp/tmp1ub3azom/mount', 'sh', '-c', 'command -v ubuntu-drivers']
2024-12-10 18:34:10,912 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', 'chroot', '/tmp/tmpan_haqv1/mount', 'sh', '-c', 'command -v ubuntu-drivers']
2024-12-10 18:34:10,945 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--property', 'PrivateMounts=yes', '--pipe', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', '/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3627.5"}}', 'in-target', '-t', '/tmp/tmp1ub3azom/mount', '--', 'ubuntu-drivers', 'list-oem']
2024-12-10 18:34:10,947 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--property', 'PrivateMounts=yes', '--pipe', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', '/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3627.6"}}', 'in-target', '-t', '/tmp/tmpan_haqv1/mount', '--', 'ubuntu-drivers', 'list', '--recommended']
2024-12-10 18:34:12,019 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step: SUCCESS: executing curtin install initial step
2024-12-10 18:34:12,020 DEBUG subiquity.models.filesystem:1923 mountpoints {'/boot/efi': 'mount-0', '/boot': 'mount-1', '/': 'mount-2'}
2024-12-10 18:34:12,020 DEBUG subiquity.models.filesystem:1913 cannot emit action to mount /boot/efi until that for /boot is emitted
2024-12-10 18:34:12,020 DEBUG subiquity.models.filesystem:1913 cannot emit action to mount /boot until that for / is emitted
2024-12-10 18:34:12,021 DEBUG subiquity.models.filesystem:1913 cannot emit action to mount /boot/efi until that for /boot is emitted
2024-12-10 18:34:12,021 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step: executing curtin install partitioning step
2024-12-10 18:34:12,026 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', '/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3627.7"}}', '-c', '/var/log/installer/curtin-install/subiquity-partitioning.conf', 'install', '--set', 'json:stages=["partitioning"]', 'cp:///tmp/tmp03_31koa/mount']
2024-12-10 18:34:12,305 DEBUG root:38 start: subiquity/OEM/load_metapackages_list/cmd-in-target: curtin command in-target
2024-12-10 18:34:12,305 DEBUG root:38 start: subiquity/Drivers/_list_drivers/cmd-in-target: curtin command in-target
2024-12-10 18:34:13,304 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install: curtin command install
2024-12-10 18:34:13,305 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning: configuring storage
2024-12-10 18:34:13,306 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin: running 'curtin block-meta simple'
2024-12-10 18:34:14,033 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: curtin command block-meta
2024-12-10 18:34:14,306 DEBUG root:38 finish: subiquity/Drivers/_list_drivers/cmd-in-target: SUCCESS: curtin command in-target
2024-12-10 18:34:14,388 DEBUG subiquity.server.curtin:130 waited 0.1 seconds for events to drain
2024-12-10 18:34:14,389 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', 'umount', '/tmp/tmpan_haqv1/mount']
2024-12-10 18:34:14,460 DEBUG subiquity.server.controllers.drivers:119 Available drivers to install: []
2024-12-10 18:34:14,460 DEBUG root:38 finish: subiquity/Drivers/_list_drivers: SUCCESS: 
2024-12-10 18:34:14,710 DEBUG subiquity.server.curtin:130 waited 0.1 seconds for events to drain
2024-12-10 18:34:14,718 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta/clear-holders: removing previous storage devices
2024-12-10 18:34:14,719 DEBUG root:38 finish: subiquity/OEM/load_metapackages_list/cmd-in-target: SUCCESS: curtin command in-target
2024-12-10 18:34:14,810 DEBUG subiquity.server.curtin:130 waited 0.2 seconds for events to drain
2024-12-10 18:34:14,811 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', 'umount', '/tmp/tmp1ub3azom/mount']
2024-12-10 18:34:14,846 DEBUG subiquity.server.controllers.oem:192 OEM meta-packages to install: []
2024-12-10 18:34:14,846 DEBUG root:38 finish: subiquity/OEM/load_metapackages_list: SUCCESS: 
2024-12-10 18:34:14,846 DEBUG root:38 finish: subiquity/OEM/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:34:14,846 DEBUG root:38 start: subiquity/TimeZone/apply_autoinstall_config: 
2024-12-10 18:34:14,847 DEBUG root:38 finish: subiquity/TimeZone/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:34:14,847 DEBUG subiquity.models.subiquity:256 model timezone for postinstall stage is configured, to go set()
2024-12-10 18:34:14,847 DEBUG subiquity.server.server:554 apply_autoinstall_config: skipping Install as interactive
2024-12-10 18:34:14,847 DEBUG root:38 start: subiquity/Updates/apply_autoinstall_config: 
2024-12-10 18:34:14,847 DEBUG root:38 finish: subiquity/Updates/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:34:14,847 DEBUG root:38 start: subiquity/Late/apply_autoinstall_config: 
2024-12-10 18:34:14,847 DEBUG root:38 finish: subiquity/Late/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:34:14,848 DEBUG root:38 start: subiquity/Shutdown/apply_autoinstall_config: 
2024-12-10 18:34:14,848 DEBUG root:38 finish: subiquity/Shutdown/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:34:14,848 INFO root:38 finish: subiquity/apply_autoinstall_config: SUCCESS: 
2024-12-10 18:34:15,413 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta/clear-holders: SUCCESS: removing previous storage devices
2024-12-10 18:34:15,413 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta/: configuring nvme_controller: nvme-controller-nvme0
2024-12-10 18:34:15,414 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta/: SUCCESS: configuring nvme_controller: nvme-controller-nvme0
2024-12-10 18:34:15,414 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring disk: disk-nvme0n1
2024-12-10 18:34:16,317 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring disk: disk-nvme0n1
2024-12-10 18:34:16,317 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring partition: partition-0
2024-12-10 18:34:16,648 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring partition: partition-0
2024-12-10 18:34:16,649 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring format: format-0
2024-12-10 18:34:16,894 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring format: format-0
2024-12-10 18:34:16,894 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring partition: partition-1
2024-12-10 18:34:17,102 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring partition: partition-1
2024-12-10 18:34:17,102 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring format: format-1
2024-12-10 18:34:17,108 DEBUG subiquity.server.controllers.integrity:61 casper-md5check results: {'checksum_missmatch': [], 'result': 'pass'}
2024-12-10 18:34:18,328 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring format: format-1
2024-12-10 18:34:18,329 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring partition: partition-2
2024-12-10 18:34:18,329 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring partition: partition-2
2024-12-10 18:34:18,329 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring lvm_volgroup: lvm_volgroup-0
2024-12-10 18:34:18,578 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring lvm_volgroup: lvm_volgroup-0
2024-12-10 18:34:18,579 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring lvm_partition: lvm_partition-0
2024-12-10 18:34:19,055 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring lvm_partition: lvm_partition-0
2024-12-10 18:34:19,055 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring format: format-2
2024-12-10 18:34:26,044 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring format: format-2
2024-12-10 18:34:26,045 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring mount: mount-2
2024-12-10 18:34:26,045 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring mount: mount-2
2024-12-10 18:34:26,045 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring mount: mount-1
2024-12-10 18:34:26,076 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring mount: mount-1
2024-12-10 18:34:26,077 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring mount: mount-0
2024-12-10 18:34:26,305 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring mount: mount-0
2024-12-10 18:34:26,305 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin: SUCCESS: running 'curtin block-meta simple'
2024-12-10 18:34:26,306 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning: SUCCESS: configuring storage
2024-12-10 18:34:26,306 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install: SUCCESS: curtin command install
2024-12-10 18:34:26,324 DEBUG subiquity.server.curtin:130 waited 0.1 seconds for events to drain
2024-12-10 18:34:26,326 DEBUG subiquity.server.controllers.filesystem:511 recording path '/dev/nvme0n1' for device disk-nvme0n1
2024-12-10 18:34:26,326 DEBUG subiquity.server.controllers.filesystem:511 recording path '/dev/nvme0n1p1' for device partition-0
2024-12-10 18:34:26,326 DEBUG subiquity.server.controllers.filesystem:511 recording path '/dev/nvme0n1p2' for device partition-1
2024-12-10 18:34:26,326 DEBUG subiquity.server.controllers.filesystem:511 recording path '/dev/nvme0n1p3' for device partition-2
2024-12-10 18:34:26,326 DEBUG subiquity.server.controllers.filesystem:511 recording path '/dev/ubuntu-vg/ubuntu-lv' for device lvm_partition-0
2024-12-10 18:34:26,327 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step: SUCCESS: executing curtin install partitioning step
2024-12-10 18:34:26,327 DEBUG subiquity.models.subiquity:533 merging config from <subiquity.models.keyboard.KeyboardModel object at 0x723588831390>
2024-12-10 18:34:26,327 DEBUG subiquity.models.subiquity:533 merging config from <subiquity.models.kernel.KernelModel object at 0x723588831360>
2024-12-10 18:34:26,328 DEBUG subiquity.models.subiquity:533 merging config from <subiquity.models.filesystem.FilesystemModel object at 0x723588b313f0>
2024-12-10 18:34:26,328 DEBUG subiquity.models.filesystem:1923 mountpoints {'/boot/efi': 'mount-0', '/boot': 'mount-1', '/': 'mount-2'}
2024-12-10 18:34:26,328 DEBUG subiquity.models.filesystem:1913 cannot emit action to mount /boot/efi until that for /boot is emitted
2024-12-10 18:34:26,328 DEBUG subiquity.models.filesystem:1913 cannot emit action to mount /boot until that for / is emitted
2024-12-10 18:34:26,328 DEBUG subiquity.models.filesystem:1913 cannot emit action to mount /boot/efi until that for /boot is emitted
2024-12-10 18:34:26,328 DEBUG subiquity.models.subiquity:533 merging config from <subiquity.models.mirror.MirrorModel object at 0x723588830a90>
2024-12-10 18:34:26,329 DEBUG subiquity.models.subiquity:533 merging config from <subiquity.models.network.NetworkModel object at 0x723588833670>
2024-12-10 18:34:26,329 DEBUG subiquity.cloudinit:38 Loaded cloud config from /run/cloud-init/combined-cloud-config.json
2024-12-10 18:34:26,330 DEBUG subiquity.models.subiquity:533 merging config from <subiquity.models.proxy.ProxyModel object at 0x7235889ab070>
2024-12-10 18:34:26,330 DEBUG subiquity.models.subiquity:533 merging config from <subiquity.models.source.SourceModel object at 0x7235889aa650>
2024-12-10 18:34:26,330 DEBUG subiquity.models.subiquity:533 merging config from <subiquity.models.subiquity.DebconfSelectionsModel object at 0x723588b310c0>
2024-12-10 18:34:26,331 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step: executing curtin install extract step
2024-12-10 18:34:26,337 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', '/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3627.8"}}', '-c', '/var/log/installer/curtin-install/subiquity-extract.conf', 'install', '--set', 'json:stages=["extract"]', 'cp:///tmp/tmp03_31koa/mount']
2024-12-10 18:34:27,304 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install: curtin command install
2024-12-10 18:34:27,305 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-extract: writing install sources to disk
2024-12-10 18:34:27,305 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-extract/builtin: running 'curtin extract'
2024-12-10 18:34:28,024 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:34:28,031 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:34:28,031 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 4}
2024-12-10 18:34:28,032 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-extract/builtin/cmd-extract: curtin command extract
2024-12-10 18:34:28,032 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-extract/builtin/cmd-extract/: acquiring and extracting image from cp:///tmp/tmp03_31koa/mount
2024-12-10 18:34:30,482 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:34:30,492 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:34:30,492 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 4, 'ssids': [(b'XXX-SCHULNETZ', 'no status'), (b'Media', 'no status'), (b'Media', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'Media', 'no status'), (b'', 'no status'), (b'', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'', 'no status'), (b'Media', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'BvSS-Surface', 'no status'), (b'BvSS-offen', 'no status'), (b'BvSS-Mobil', 'no status'), (b'WRS-offen', 'no status'), (b'WRS-Mobil', 'no status')]}
2024-12-10 18:34:30,492 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:34:30,501 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:35:11,004 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:35:11,010 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:35:11,010 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 4}
2024-12-10 18:35:13,446 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:35:13,455 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:35:13,455 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 4, 'ssids': [(b'XXX-SCHULNETZ', 'no status'), (b'Media', 'no status'), (b'Media', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'Media', 'no status'), (b'', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'', 'no status'), (b'Media', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'', 'no status'), (b'BvSS-Mobil', 'no status')]}
2024-12-10 18:35:13,455 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:35:13,462 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:36:07,391 DEBUG subiquity.server.curtin:130 waited 0.1 seconds for events to drain
2024-12-10 18:36:07,492 DEBUG subiquity.server.curtin:130 waited 0.2 seconds for events to drain
2024-12-10 18:36:07,555 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-extract/builtin/cmd-extract/: SUCCESS: acquiring and extracting image from cp:///tmp/tmp03_31koa/mount
2024-12-10 18:36:07,555 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-extract/builtin: SUCCESS: running 'curtin extract'
2024-12-10 18:36:07,555 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-extract: SUCCESS: writing install sources to disk
2024-12-10 18:36:07,556 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install: SUCCESS: curtin command install
2024-12-10 18:36:07,594 DEBUG subiquity.server.curtin:130 waited 0.30000000000000004 seconds for events to drain
2024-12-10 18:36:07,594 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step: SUCCESS: executing curtin install extract step
2024-12-10 18:36:07,595 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', 'mount', '-o', 'bind', '/cdrom', '/target/cdrom']
2024-12-10 18:36:07,636 DEBUG root:38 start: subiquity/Install/install/curtin_install/setup_target: configuring keyboard
2024-12-10 18:36:07,637 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', '/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3627.9"}}', 'in-target', '-t', '/target', '--', 'setupcon', '--save-only']
2024-12-10 18:36:08,306 DEBUG root:38 start: subiquity/Install/install/curtin_install/setup_target/cmd-in-target: curtin command in-target
2024-12-10 18:36:08,808 DEBUG subiquity.server.curtin:130 waited 0.1 seconds for events to drain
2024-12-10 18:36:08,908 DEBUG subiquity.server.curtin:130 waited 0.2 seconds for events to drain
2024-12-10 18:36:09,009 DEBUG subiquity.server.curtin:130 waited 0.30000000000000004 seconds for events to drain
2024-12-10 18:36:09,056 DEBUG root:38 finish: subiquity/Install/install/curtin_install/setup_target/cmd-in-target: SUCCESS: curtin command in-target
2024-12-10 18:36:09,109 DEBUG subiquity.server.curtin:130 waited 0.4 seconds for events to drain
2024-12-10 18:36:09,110 DEBUG root:38 finish: subiquity/Install/install/curtin_install/setup_target: SUCCESS: configuring keyboard
2024-12-10 18:36:09,110 DEBUG subiquitycore.utils:119 arun_command called: ['grep-status', '--whole-pkg', '-FProvides', 'linux-image', '--and', '-FStatus', 'installed', '--show-field=Package', '--no-field-names', '/target/var/lib/dpkg/status']
2024-12-10 18:36:09,124 DEBUG subiquitycore.utils:133 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
2024-12-10 18:36:09,124 DEBUG subiquity.models.subiquity:533 merging config from <subiquity.models.keyboard.KeyboardModel object at 0x723588831390>
2024-12-10 18:36:09,124 DEBUG subiquity.models.subiquity:533 merging config from <subiquity.models.kernel.KernelModel object at 0x723588831360>
2024-12-10 18:36:09,124 DEBUG subiquity.models.subiquity:533 merging config from <subiquity.models.filesystem.FilesystemModel object at 0x723588b313f0>
2024-12-10 18:36:09,124 DEBUG subiquity.models.filesystem:1923 mountpoints {'/boot/efi': 'mount-0', '/boot': 'mount-1', '/': 'mount-2'}
2024-12-10 18:36:09,124 DEBUG subiquity.models.filesystem:1913 cannot emit action to mount /boot/efi until that for /boot is emitted
2024-12-10 18:36:09,124 DEBUG subiquity.models.filesystem:1913 cannot emit action to mount /boot until that for / is emitted
2024-12-10 18:36:09,125 DEBUG subiquity.models.filesystem:1913 cannot emit action to mount /boot/efi until that for /boot is emitted
2024-12-10 18:36:09,125 DEBUG subiquity.models.subiquity:533 merging config from <subiquity.models.mirror.MirrorModel object at 0x723588830a90>
2024-12-10 18:36:09,125 DEBUG subiquity.models.subiquity:533 merging config from <subiquity.models.network.NetworkModel object at 0x723588833670>
2024-12-10 18:36:09,125 DEBUG subiquity.cloudinit:38 Loaded cloud config from /run/cloud-init/combined-cloud-config.json
2024-12-10 18:36:09,125 DEBUG subiquity.models.subiquity:533 merging config from <subiquity.models.proxy.ProxyModel object at 0x7235889ab070>
2024-12-10 18:36:09,125 DEBUG subiquity.models.subiquity:533 merging config from <subiquity.models.source.SourceModel object at 0x7235889aa650>
2024-12-10 18:36:09,125 DEBUG subiquity.models.subiquity:533 merging config from <subiquity.models.subiquity.DebconfSelectionsModel object at 0x723588b310c0>
2024-12-10 18:36:09,126 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step: executing curtin install curthooks step
2024-12-10 18:36:09,131 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', '/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3627.10"}}', '-c', '/var/log/installer/curtin-install/subiquity-curthooks.conf', 'install', '--set', 'json:stages=["curthooks"]']
2024-12-10 18:36:10,055 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install: curtin command install
2024-12-10 18:36:10,055 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-curthooks: configuring installed system
2024-12-10 18:36:10,055 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-curthooks/builtin: running 'curtin curthooks'
2024-12-10 18:36:10,630 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-curthooks/builtin/cmd-curthooks: curtin command curthooks
2024-12-10 18:36:10,630 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/writing-apt-config: configuring apt configuring apt
2024-12-10 18:36:10,631 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/writing-apt-config: SUCCESS: configuring apt configuring apt
2024-12-10 18:36:10,631 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/installing-missing-packages: installing missing packages
2024-12-10 18:36:10,642 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/: Installing packages on target system: ['efibootmgr', 'grub-efi-amd64', 'grub-efi-amd64-signed', 'lvm2', 'shim-signed']
2024-12-10 18:36:15,059 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:36:15,066 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:36:15,066 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 4}
2024-12-10 18:36:17,516 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:36:17,522 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:36:17,523 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 4, 'ssids': [(b'XXX-SCHULNETZ', 'no status'), (b'Media', 'no status'), (b'Media', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'Media', 'no status'), (b'', 'no status'), (b'', 'no status'), (b'Media', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'', 'no status'), (b'', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status')]}
2024-12-10 18:36:17,523 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:36:17,528 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:36:23,114 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/: SUCCESS: Installing packages on target system: ['efibootmgr', 'grub-efi-amd64', 'grub-efi-amd64-signed', 'lvm2', 'shim-signed']
2024-12-10 18:36:23,114 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/installing-missing-packages: SUCCESS: installing missing packages
2024-12-10 18:36:23,114 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-iscsi-service: configuring iscsi service
2024-12-10 18:36:23,114 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-iscsi-service: SUCCESS: configuring iscsi service
2024-12-10 18:36:23,115 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-mdadm-service: configuring raid (mdadm) service
2024-12-10 18:36:23,115 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-mdadm-service: SUCCESS: configuring raid (mdadm) service
2024-12-10 18:36:23,115 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-nvme-over-tcp: configuring NVMe over TCP
2024-12-10 18:36:23,115 DEBUG root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-nvme-over-tcp: SUCCESS: configuring NVMe over TCP
2024-12-10 18:36:23,115 DEBUG root:38 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/installing-kernel: installing kernel
2024-12-10 18:36:24,835 ERROR root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/installing-kernel: FAIL: installing kernel
2024-12-10 18:36:24,836 ERROR root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-curthooks/builtin: FAIL: running 'curtin curthooks'
2024-12-10 18:36:24,836 ERROR root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-curthooks: FAIL: configuring installed system
2024-12-10 18:36:25,696 ERROR root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step: FAIL: 
2024-12-10 18:36:25,696 ERROR root:38 finish: subiquity/Install/install/curtin_install: FAIL: 
2024-12-10 18:36:25,697 DEBUG subiquity.common.errorreport:398 generating crash report
2024-12-10 18:36:25,714 INFO subiquity.common.errorreport:424 saving crash report 'curthooks crashed with CurtinInstallError' to /var/crash/1733855785.697485209.install_fail.crash
2024-12-10 18:36:25,714 ERROR root:38 finish: subiquity/Install/install: FAIL: 
2024-12-10 18:36:25,715 INFO root:38 start: subiquity/ErrorReporter/1733855785.697485209.install_fail/add_info: 
2024-12-10 18:36:25,715 ERROR subiquity.server.server:494 top level error
Traceback (most recent call last):
  File "/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/subiquity/server/controllers/install.py", line 239, in run_curtin_step
    await run_curtin_command(
  File "/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/subiquity/server/curtin.py", line 230, in run_curtin_command
    return await cmd.wait()
  File "/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/subiquity/server/curtin.py", line 125, in wait
    result = await self.runner.wait(self.proc)
  File "/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/subiquity/server/runner.py", line 94, in wait
    raise subprocess.CalledProcessError(
subprocess.CalledProcessError: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3627', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/237/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/sbin:/snap/ubuntu-desktop-bootstrap/237/usr/bin:/snap/ubuntu-desktop-bootstrap/237/sbin:/snap/ubuntu-desktop-bootstrap/237/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/237/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/237/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/237', '--', '/snap/ubuntu-desktop-bootstrap/237/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3627.10"}}', '-c', '/var/log/installer/curtin-install/subiquity-curthooks.conf', 'install', '--set', 'json:stages=["curthooks"]']' returned non-zero exit status 3.

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/subiquity/server/controllers/shutdown.py", line 72, in _wait_install
    await self.app.controllers.Install.install_task
  File "/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/subiquitycore/context.py", line 166, in decorated_async
    return await meth(self, **kw)
  File "/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/subiquity/server/controllers/install.py", line 610, in install
    await self.curtin_install(context=context, source=for_install_path)
  File "/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/subiquitycore/context.py", line 166, in decorated_async
    return await meth(self, **kw)
  File "/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/subiquity/server/controllers/install.py", line 435, in curtin_install
    await run_curtin_step(
  File "/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/subiquity/server/controllers/install.py", line 333, in run_curtin_step
    await self.run_curtin_step(
  File "/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/subiquitycore/context.py", line 166, in decorated_async
    return await meth(self, **kw)
  File "/snap/ubuntu-desktop-bootstrap/237/bin/subiquity/subiquity/server/controllers/install.py", line 250, in run_curtin_step
    raise CurtinInstallError(stages=stages)
subiquity.server.controllers.install.CurtinInstallError
2024-12-10 18:36:26,010 ERROR root:38 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install: FAIL: curtin command install
2024-12-10 18:36:28,525 INFO root:38 finish: subiquity/ErrorReporter/1733855785.697485209.install_fail/add_info: SUCCESS: written to /var/crash/1733855785.697485209.install_fail.crash
2024-12-10 18:36:28,526 INFO root:38 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "ERROR", "confirming_tty": "", "error": {"state": "DONE", "base": "...
2024-12-10 18:36:28,526 INFO root:38 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "ERROR", "confirming_tty": "", "error": {"state": "DONE", "base": "...
2024-12-10 18:36:28,527 INFO root:38 start: subiquity/Meta/status_GET: 
2024-12-10 18:36:28,527 INFO root:38 start: subiquity/Meta/status_GET: 
2024-12-10 18:36:28,549 INFO root:38 start: subiquity/Meta/status_GET: 
2024-12-10 18:36:28,549 INFO root:38 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "ERROR", "confirming_tty": "", "error": {"state": "DONE", "base": "...
2024-12-10 18:37:50,010 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:37:50,022 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:37:50,022 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 4}
2024-12-10 18:37:52,447 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:37:52,454 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:37:52,454 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 4, 'ssids': [(b'XXX-SCHULNETZ', 'no status'), (b'Media', 'no status'), (b'Media', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'Media', 'no status'), (b'', 'no status'), (b'', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'', 'no status'), (b'', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'', 'no status'), (b'XXX-SCHUELER', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'XXX-SCHULNETZ', 'no status'), (b'BvSS-Surface', 'no status'), (b'BvSS-offen', 'no status'), (b'BvSS-Mobil', 'no status'), (b'WRS-offen', 'no status'), (b'WRS-Mobil', 'no status')]}
2024-12-10 18:37:52,454 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:37:52,462 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:37:59,269 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-12-10 18:37:59,280 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-12-10 18:37:59,280 DEBUG probert.network:585 event for addr_change: DEL {'ifindex': 3, 'flags': 513, 'family': 2, 'scope': 0, 'local': b'172.20.0.105/24'}
2024-12-10 18:37:59,280 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 3, 'flags': 513, 'family': 2, 'scope': 0, 'local': b'172.20.0.105/24'}
2024-12-10 18:37:59,280 DEBUG probert.network:717 addr_change NEW {'ifindex': 3, 'flags': 513, 'family': 2, 'scope': 0, 'local': b'172.20.0.105/24'}
2024-12-10 18:37:59,280 DEBUG root:38 start: subiquity/Network/_send_update: CHANGE enx3c18a0162c27
2024-12-10 18:37:59,281 DEBUG root:38 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enx3c18a0162c27
2024-12-10 18:37:59,281 DEBUG probert.network:731 route_change DEL {'family': 2, 'type': 2, 'table': 255, 'dst': b'172.20.0.105', 'ifindex': 3}
2024-12-10 18:37:59,281 DEBUG probert.network:731 route_change DEL {'family': 2, 'type': 1, 'table': 254, 'dst': b'172.20.0.0/24', 'ifindex': 3}
2024-12-10 18:37:59,281 DEBUG probert.network:731 route_change DEL {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 3}
2024-12-10 18:37:59,299 DEBUG pyroute2.ndb.125574252159872.sources.localhost:74 init
2024-12-10 18:37:59,299 DEBUG pyroute2.ndb.125574252159872.sources.localhost:384 starting the source
2024-12-10 18:37:59,299 DEBUG pyroute2.ndb.125574252159872.sources.localhost/nsmanager:74 init
2024-12-10 18:37:59,300 DEBUG pyroute2.ndb.125574252159872.sources.localhost:74 connecting
2024-12-10 18:37:59,300 DEBUG pyroute2.ndb.125574252159872.sources.localhost/nsmanager:384 starting the source
2024-12-10 18:37:59,300 DEBUG pyroute2.ndb.125574252159872.sources.localhost/nsmanager:74 connecting
2024-12-10 18:37:59,300 DEBUG pyroute2.ndb.125574252159872.sources.localhost:74 loading
2024-12-10 18:37:59,301 DEBUG pyroute2.ndb.125574252159872.sources.localhost/nsmanager:74 loading
2024-12-10 18:37:59,310 DEBUG pyroute2.ndb.125574252159872.sources.localhost:74 running
2024-12-10 18:37:59,311 DEBUG pyroute2.ndb.125574252159872.sources.localhost/nsmanager:74 running
2024-12-10 18:37:59,312 DEBUG pyroute2.ndb.125574252159872.sources.localhost:399 source shutdown
2024-12-10 18:37:59,312 DEBUG pyroute2.ndb.125574252159872.sources.localhost:374 sync
2024-12-10 18:37:59,312 DEBUG pyroute2.ndb.125574252159872.sources.localhost:369 shutdown handled by the main thread
2024-12-10 18:37:59,312 DEBUG pyroute2.ndb.125574252159872.sources.localhost:74 stopped
2024-12-10 18:37:59,312 DEBUG pyroute2.ndb.125574252159872.main:851 flush DB for the target localhost
2024-12-10 18:37:59,313 DEBUG pyroute2.ndb.125574252159872.sources.localhost/nsmanager:399 source shutdown
2024-12-10 18:37:59,317 DEBUG pyroute2.ndb.125574252159872.sources.localhost/nsmanager:374 sync
2024-12-10 18:37:59,317 DEBUG pyroute2.ndb.125574252159872.sources.localhost/nsmanager:369 shutdown handled by the main thread
2024-12-10 18:37:59,317 DEBUG pyroute2.ndb.125574252159872.sources.localhost/nsmanager:74 stopped
2024-12-10 18:37:59,317 DEBUG pyroute2.ndb.125574252159872.main:851 flush DB for the target localhost/nsmanager
2024-12-10 18:37:59,318 DEBUG subiquitycore.controllers.network:103 default routes True
2024-12-10 18:37:59,318 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'172.20.0.105', 'ifindex': 3}
2024-12-10 18:37:59,318 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'172.20.0.0/24', 'ifindex': 3}
2024-12-10 18:37:59,318 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 3}
2024-12-10 18:37:59,325 DEBUG pyroute2.ndb.125574265727120.sources.localhost:74 init
2024-12-10 18:37:59,325 DEBUG pyroute2.ndb.125574265727120.sources.localhost:384 starting the source
2024-12-10 18:37:59,326 DEBUG pyroute2.ndb.125574265727120.sources.localhost/nsmanager:74 init
2024-12-10 18:37:59,326 DEBUG pyroute2.ndb.125574265727120.sources.localhost:74 connecting
2024-12-10 18:37:59,326 DEBUG pyroute2.ndb.125574265727120.sources.localhost/nsmanager:384 starting the source
2024-12-10 18:37:59,326 DEBUG pyroute2.ndb.125574265727120.sources.localhost:74 loading
2024-12-10 18:37:59,326 DEBUG pyroute2.ndb.125574265727120.sources.localhost/nsmanager:74 connecting
2024-12-10 18:37:59,328 DEBUG pyroute2.ndb.125574265727120.sources.localhost/nsmanager:74 loading
2024-12-10 18:37:59,337 DEBUG pyroute2.ndb.125574265727120.sources.localhost:74 running
2024-12-10 18:37:59,338 DEBUG pyroute2.ndb.125574265727120.sources.localhost/nsmanager:74 running
2024-12-10 18:37:59,338 DEBUG pyroute2.ndb.125574265727120.sources.localhost:399 source shutdown
2024-12-10 18:37:59,338 DEBUG pyroute2.ndb.125574265727120.sources.localhost:374 sync
2024-12-10 18:37:59,339 DEBUG pyroute2.ndb.125574265727120.sources.localhost:369 shutdown handled by the main thread
2024-12-10 18:37:59,339 DEBUG pyroute2.ndb.125574265727120.sources.localhost:74 stopped
2024-12-10 18:37:59,339 DEBUG pyroute2.ndb.125574265727120.main:851 flush DB for the target localhost
2024-12-10 18:37:59,339 DEBUG pyroute2.ndb.125574265727120.sources.localhost/nsmanager:399 source shutdown
2024-12-10 18:37:59,343 DEBUG pyroute2.ndb.125574265727120.sources.localhost/nsmanager:374 sync
2024-12-10 18:37:59,343 DEBUG pyroute2.ndb.125574265727120.sources.localhost/nsmanager:369 shutdown handled by the main thread
2024-12-10 18:37:59,343 DEBUG pyroute2.ndb.125574265727120.sources.localhost/nsmanager:74 stopped
2024-12-10 18:37:59,343 DEBUG pyroute2.ndb.125574265727120.main:851 flush DB for the target localhost/nsmanager
2024-12-10 18:37:59,344 DEBUG subiquitycore.controllers.network:103 default routes True
Antworten |