mac02: building 7c54ln8knxbmi58jcb463knfyy2arlla-vm-test-debian_12.drv Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.02 seconds) Test will time out and terminate in 3600 seconds run the VM test script additionally exposed symbols: vm, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh vm: waiting for unit multi-user.target vm: waiting for the VM to finish booting vm: starting vm vm: QEMU running (pid 43889) vm # /nix/var/nix/builds/nix-43746-1369613090 /nix/var/nix/builds/nix-43746-1369613090/vm-state-vm vm # /nix/var/nix/builds/nix-43746-1369613090/vm-state-vm vm # UEFI firmware (version edk2-stable202408-prebuilt.qemu.org built at 16:28:50 on Sep 12 2024) vm # ArmTrngLib could not be correctly initialized. vm # Error: Image at 0007FDB6000 start failed: 00000001 vm # Error: Image at 0007FD6D000 start failed: Not Found vm # Error: Image at 0007FCBA000 start failed: Unsupported vm # Error: Image at 0007FC3F000 start failed: Not Found vm # Error: Image at 0007FB65000 start failed: Aborted vm # Tpm2SubmitCommand - Tcg2 - Not Found vm # Tpm2GetCapabilityPcrs fail! vm # Tpm2SubmitCommand - Tcg2 - Not Found vm: Guest shell says: b'\x1b[2J\x1b[01;01H\x1b[=3h\x1b[2J\x1b[01;01H\x1b[2J\x1b[01;01H\x1b[=3h\x1b[2J\x1b[01;01H' vm # BdsDxe: loading Boot0001 "UEFI Misc Device" from PciRoot(0x0)/Pci(0x2,0x0) vm # BdsDxe: starting Boot0001 "UEFI Misc Device" from PciRoot(0x0)/Pci(0x2,0x0) vm: Guest shell says: b'BdsDxe: loading Boot0001 "UEFI Misc Device" from PciRoot(0x0)/Pci(0x2,0x0)\r\nBdsDxe: starting Boot0001 "UEFI Misc Device" from Pc' vm: Guest shell says: b'iRoot(0x0)/Pci(0x2,0x0)\r\n' vm # Welcome to GRUB! vm # vm # error: no suitable video mode found. vm: Guest shell says: b'\x1b[0m\x1b[30m\x1b[47mWelcome to GRUB!\n\r\n\r\x1b[0m\x1b[37m\x1b[40m\x1b[0m\x1b[30m\x1b[40m\x1b[2J\x1b[01;01H\x1b[0m\x1b[37m\x1b[40merror: no suitable video mode found.\n\r\x1b[' vm # Booting `Debian GNU/Linux' vm # vm # Loading Linux 6.1.0-53-arm64 ... vm: Guest shell says: b"0m\x1b[30m\x1b[40m\x1b[2J\x1b[01;01H\x1b[0m\x1b[37m\x1b[40m Booting `Debian GNU/Linux'\n\r\n\rLoading Linux 6.1.0-53-arm64 ...\n\r" vm # Loading initial ramdisk ... vm: Guest shell says: b'Loading initial ramdisk ...\n\r' vm # EFI stub: Booting Linux Kernel... vm # EFI stub: Using DTB from configuration table vm # EFI stub: Exiting boot services... vm # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x610f0000] vm # [ 0.000000] Linux version 6.1.0-53-arm64 (debian-kernel@lists.debian.org) (gcc-12 (Debian 12.2.0-14+deb12u1) 12.2.0, GNU ld (GNU Binutils for Debian) 2.40) #1 SMP Debian 6.1.187-1 (2026-09-07) vm # [ 0.000000] efi: EFI v2.70 by EDK II vm # [ 0.000000] efi: SMBIOS 3.0=0x7fed0000 MEMATTR=0x7f02d118 ACPI 2.0=0x7cb43018 MOKvar=0x7c660000 RNG=0x7cb43998 MEMRESERVE=0x7c68cf18 vm # [ 0.000000] random: crng init done vm # [ 0.000000] secureboot: Secure boot disabled vm # [ 0.000000] ACPI: Early table checksum verification disabled vm # [ 0.000000] ACPI: RSDP 0x000000007CB43018 000024 (v02 BOCHS ) vm # [ 0.000000] ACPI: XSDT 0x000000007CB43F18 000064 (v01 BOCHS BXPC 00000001 01000013) vm # [ 0.000000] ACPI: FACP 0x000000007CB43B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) vm # [ 0.000000] ACPI: DSDT 0x000000007CB41018 00156C (v02 BOCHS BXPC 00000001 BXPC 00000001) vm # [ 0.000000] ACPI: APIC 0x000000007CB43C98 0000FC (v04 BOCHS BXPC 00000001 BXPC 00000001) vm # [ 0.000000] ACPI: PPTT 0x000000007CB43E18 000074 (v02 BOCHS BXPC 00000001 BXPC 00000001) vm # [ 0.000000] ACPI: GTDT 0x000000007CB43098 000068 (v03 BOCHS BXPC 00000001 BXPC 00000001) vm # [ 0.000000] ACPI: MCFG 0x000000007CB43A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) vm # [ 0.000000] ACPI: SPCR 0x000000007CB43818 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) vm # [ 0.000000] ACPI: DBG2 0x000000007CB43A18 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) vm # [ 0.000000] ACPI: IORT 0x000000007CB43898 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) vm # [ 0.000000] ACPI: SPCR: console: pl011,mmio32,0x9000000,9600 vm # [ 0.000000] NUMA: Failed to initialise from firmware vm # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] vm # [ 0.000000] NUMA: NODE_DATA [mem 0x7fc49380-0x7fc4bfff] vm # [ 0.000000] Zone ranges: vm # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] vm # [ 0.000000] DMA32 empty vm # [ 0.000000] Normal empty vm # [ 0.000000] Movable zone start for each node vm # [ 0.000000] Early memory node ranges vm # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007c65ffff] vm # [ 0.000000] node 0: [mem 0x000000007c660000-0x000000007c66ffff] vm # [ 0.000000] node 0: [mem 0x000000007c670000-0x000000007c76ffff] vm # [ 0.000000] node 0: [mem 0x000000007c770000-0x000000007cb3ffff] vm # [ 0.000000] node 0: [mem 0x000000007cb40000-0x000000007fe1ffff] vm # [ 0.000000] node 0: [mem 0x000000007fe20000-0x000000007feaffff] vm # [ 0.000000] node 0: [mem 0x000000007feb0000-0x000000007febffff] vm # [ 0.000000] node 0: [mem 0x000000007fec0000-0x000000007ffdffff] vm # [ 0.000000] node 0: [mem 0x000000007ffe0000-0x000000007fffffff] vm # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] vm # [ 0.000000] cma: Reserved 64 MiB at 0x0000000078600000 vm # [ 0.000000] psci: probing for conduit method from ACPI. vm # [ 0.000000] psci: PSCIv1.1 detected in firmware. vm # [ 0.000000] psci: Using standard PSCI v0.2 function IDs vm # [ 0.000000] psci: Trusted OS migration not required vm # [ 0.000000] psci: SMC Calling Convention v1.0 vm # [ 0.000000] percpu: Embedded 31 pages/cpu s87208 r8192 d31576 u126976 vm # [ 0.000000] Detected PIPT I-cache on CPU0 vm # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm) vm # [ 0.000000] CPU features: detected: Spectre-v4 vm # [ 0.000000] CPU features: detected: Spectre-BHB vm # [ 0.000000] alternatives: applying boot alternatives vm # [ 0.000000] Fallback order for Node 0: 0 vm # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 258048 vm # [ 0.000000] Policy zone: DMA vm # [ 0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-6.1.0-53-arm64 root=PARTUUID=371208bc-c854-4cc3-af00-a379861a139d ro vm # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) vm # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) vm # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off vm # [ 0.000000] Memory: 131072K/1048576K available (13248K kernel code, 2806K rwdata, 9512K rodata, 6464K init, 627K bss, 89336K reserved, 65536K cma-reserved) vm # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 vm # [ 0.000000] ftrace: allocating 44217 entries in 173 pages vm # [ 0.000000] ftrace: allocated 173 pages with 5 groups vm # [ 0.000000] trace event string verifier disabled vm # [ 0.000000] rcu: Hierarchical RCU implementation. vm # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=2. vm # [ 0.000000] Rude variant of Tasks RCU enabled. vm # [ 0.000000] Tracing variant of Tasks RCU enabled. vm # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. vm # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 vm # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 vm # [ 0.000000] Root IRQ handler: gic_handle_irq vm # [ 0.000000] GICv2m: ACPI overriding V2M MSI_TYPER (base:80, num:64) vm # [ 0.000000] GICv2m: range[mem 0x08020000-0x08020fff], SPI[80:143] vm # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. vm # [ 0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (virt). vm # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns vm # [ 0.000000] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns vm # [ 0.000028] Console: colour dummy device 80x25 vm # [ 0.000036] ACPI: Core revision 20220331 vm # [ 0.000050] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000) vm # [ 0.000051] pid_max: default: 32768 minimum: 301 vm # [ 0.000060] LSM: Security Framework initializing vm # [ 0.000064] landlock: Up and running. vm # [ 0.000064] Yama: disabled by default; enable with sysctl kernel.yama.* vm # [ 0.000074] AppArmor: AppArmor initialized vm # [ 0.000075] TOMOYO Linux initialized vm # [ 0.000077] LSM support for eBPF active vm # [ 0.000084] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) vm # [ 0.000086] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) vm # [ 0.000213] Early cacheinfo failed, ret = -2 vm # [ 0.000271] cblist_init_generic: Setting adjustable number of callback queues. vm # [ 0.000272] cblist_init_generic: Setting shift to 1 and lim to 1. vm # [ 0.000277] cblist_init_generic: Setting adjustable number of callback queues. vm # [ 0.000277] cblist_init_generic: Setting shift to 1 and lim to 1. vm # [ 0.000293] rcu: Hierarchical SRCU implementation. vm # [ 0.000294] rcu: Max phase no-delay instances is 1000. vm # [ 0.000394] Remapping and enabling EFI services. vm # [ 0.000504] smp: Bringing up secondary CPUs ... vm # [ 0.000629] Detected PIPT I-cache on CPU1 vm # [ 0.000684] CPU1: Booted secondary processor 0x0000000001 [0x610f0000] vm # [ 0.000760] smp: Brought up 1 node, 2 CPUs vm # [ 0.000760] SMP: Total of 2 processors activated. vm # [ 0.000761] CPU features: detected: ARMv8.4 Translation Table Level vm # [ 0.000761] CPU features: detected: Data cache clean to the PoU not required for I/D coherence vm # [ 0.000761] CPU features: detected: Common not Private translations vm # [ 0.000762] CPU features: detected: CRC32 instructions vm # [ 0.000762] CPU features: detected: Data cache clean to Point of Deep Persistence vm # [ 0.000762] CPU features: detected: Data cache clean to Point of Persistence vm # [ 0.000762] CPU features: detected: E0PD vm # [ 0.000762] CPU features: detected: Enhanced Privileged Access Never vm # [ 0.000763] CPU features: detected: Generic authentication (IMP DEF algorithm) vm # [ 0.000763] CPU features: detected: RCpc load-acquire (LDAPR) vm # [ 0.000763] CPU features: detected: LSE atomic instructions vm # [ 0.000763] CPU features: detected: Privileged Access Never vm # [ 0.000763] CPU features: detected: RAS Extension Support vm # [ 0.000764] CPU features: detected: Speculation barrier (SB) vm # [ 0.000764] CPU features: detected: TLB range maintenance instructions vm # [ 0.000794] CPU: All CPU(s) started at EL1 vm # [ 0.000795] alternatives: applying system-wide alternatives vm # [ 0.001857] node 0 deferred pages initialised in 0ms vm # [ 0.002000] devtmpfs: initialized vm # [ 0.002126] Registered cp15_barrier emulation handler vm # [ 0.002127] setend instruction emulation is not supported on this system vm # [ 0.002143] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns vm # [ 0.002147] futex hash table entries: 512 (order: 3, 32768 bytes, linear) vm # [ 0.002223] pinctrl core: initialized pinctrl subsystem vm # [ 0.002270] SMBIOS 3.0.0 present. vm # [ 0.002271] DMI: QEMU QEMU Virtual Machine, BIOS edk2-stable202408-prebuilt.qemu.org 08/13/2024 vm # [ 0.002324] NET: Registered PF_NETLINK/PF_ROUTE protocol family vm # [ 0.002589] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations vm # [ 0.002597] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations vm # [ 0.002605] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations vm # [ 0.002607] audit: initializing netlink subsys (disabled) vm # [ 0.002654] audit: type=2000 audit(0.000:1): state=initialized audit_enabled=0 res=1 vm # [ 0.002685] thermal_sys: Registered thermal governor 'fair_share' vm # [ 0.002686] thermal_sys: Registered thermal governor 'bang_bang' vm # [ 0.002686] thermal_sys: Registered thermal governor 'step_wise' vm # [ 0.002686] thermal_sys: Registered thermal governor 'user_space' vm # [ 0.002686] thermal_sys: Registered thermal governor 'power_allocator' vm # [ 0.002689] cpuidle: using governor ladder vm # [ 0.002689] cpuidle: using governor menu vm # [ 0.002697] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. vm # [ 0.002728] ASID allocator initialised with 65536 entries vm # [ 0.002739] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 vm # [ 0.002756] Serial: AMBA PL011 UART driver vm # [ 0.002784] KASLR enabled vm # [ 0.004548] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages vm # [ 0.004549] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page vm # [ 0.004549] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages vm # [ 0.004549] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page vm # [ 0.004550] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages vm # [ 0.004550] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page vm # [ 0.004550] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages vm # [ 0.004550] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page vm # [ 0.004819] ACPI: Added _OSI(Module Device) vm # [ 0.004820] ACPI: Added _OSI(Processor Device) vm # [ 0.004820] ACPI: Added _OSI(Processor Aggregator Device) vm # [ 0.005010] ACPI: 1 ACPI AML tables successfully acquired and loaded vm # [ 0.005080] ACPI: Interpreter enabled vm # [ 0.005081] ACPI: Using GIC for interrupt routing vm # [ 0.005083] ACPI: MCFG table detected, 1 entries vm # [ 0.005581] ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA vm # [ 0.033020] printk: console [ttyAMA0] enabled vm # [ 0.033550] ACPI: PCI: Interrupt link L000 configured for IRQ 35 vm # [ 0.033723] ACPI: PCI: Interrupt link L001 configured for IRQ 36 vm # [ 0.033886] ACPI: PCI: Interrupt link L002 configured for IRQ 37 vm # [ 0.034055] ACPI: PCI: Interrupt link L003 configured for IRQ 38 vm # [ 0.034222] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) vm # [ 0.034401] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] vm # [ 0.034666] acpi PNP0A08:00: _OSC: platform does not support [LTR] vm # [ 0.034853] acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug SHPCHotplug PME AER PCIeCapability] vm # [ 0.035138] acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 vm # [ 0.035380] acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] vm # [ 0.035596] ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] vm # [ 0.035841] PCI host bridge to bus 0000:00 vm # [ 0.035957] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] vm # [ 0.036169] pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] vm # [ 0.036355] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] vm # [ 0.036574] pci_bus 0000:00: root bus resource [bus 00-ff] vm # [ 0.036738] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 vm # [ 0.036986] pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 vm # [ 0.037310] pci 0000:00:01.0: BAR 0 [io 0x0180-0x019f] vm # [ 0.037535] pci 0000:00:01.0: BAR 1 [mem 0x10007000-0x10007fff] vm # [ 0.037962] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] vm # [ 0.038341] pci 0000:00:02.0: [1af4:1001] type 00 class 0x010000 vm # [ 0.038602] pci 0000:00:02.0: BAR 0 [io 0x0000-0x007f] vm # [ 0.038857] pci 0000:00:02.0: BAR 1 [mem 0x10006000-0x10006fff] vm # [ 0.039292] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref] vm # [ 0.039685] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 vm # [ 0.039965] pci 0000:00:03.0: BAR 0 [io 0x0160-0x017f] vm # [ 0.040190] pci 0000:00:03.0: BAR 1 [mem 0x10005000-0x10005fff] vm # [ 0.040612] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref] vm # [ 0.040995] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 vm # [ 0.041269] pci 0000:00:04.0: BAR 0 [io 0x00c0-0x00ff] vm # [ 0.041502] pci 0000:00:04.0: BAR 1 [mem 0x10004000-0x10004fff] vm # [ 0.041926] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref] vm # [ 0.042301] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 vm # [ 0.042561] pci 0000:00:05.0: BAR 0 [io 0x0140-0x015f] vm # [ 0.042793] pci 0000:00:05.0: BAR 1 [mem 0x10003000-0x10003fff] vm # [ 0.043207] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref] vm # [ 0.043576] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 vm # [ 0.043830] pci 0000:00:06.0: BAR 0 [io 0x0120-0x013f] vm # [ 0.044060] pci 0000:00:06.0: BAR 1 [mem 0x10002000-0x10002fff] vm # [ 0.044468] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref] vm # [ 0.044837] pci 0000:00:07.0: [1af4:1003] type 00 class 0x078000 vm # [ 0.045094] pci 0000:00:07.0: BAR 0 [io 0x0080-0x00bf] vm # [ 0.045336] pci 0000:00:07.0: BAR 1 [mem 0x10001000-0x10001fff] vm # [ 0.045766] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref] vm # [ 0.046151] pci 0000:00:08.0: [1af4:1005] type 00 class 0x00ff00 vm # [ 0.046409] pci 0000:00:08.0: BAR 0 [io 0x0100-0x011f] vm # [ 0.046644] pci 0000:00:08.0: BAR 1 [mem 0x10000000-0x10000fff] vm # [ 0.047058] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref] vm # [ 0.047451] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned vm # [ 0.047693] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned vm # [ 0.047933] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned vm # [ 0.048173] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned vm # [ 0.048412] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned vm # [ 0.048649] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned vm # [ 0.048883] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned vm # [ 0.049113] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned vm # [ 0.049367] pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned vm # [ 0.049565] pci 0000:00:02.0: BAR 1 [mem 0x10001000-0x10001fff]: assigned vm # [ 0.049758] pci 0000:00:03.0: BAR 1 [mem 0x10002000-0x10002fff]: assigned vm # [ 0.049995] pci 0000:00:04.0: BAR 1 [mem 0x10003000-0x10003fff]: assigned vm # [ 0.050191] pci 0000:00:05.0: BAR 1 [mem 0x10004000-0x10004fff]: assigned vm # [ 0.050965] pci 0000:00:06.0: BAR 1 [mem 0x10005000-0x10005fff]: assigned vm # [ 0.051162] pci 0000:00:07.0: BAR 1 [mem 0x10006000-0x10006fff]: assigned vm # [ 0.051356] pci 0000:00:08.0: BAR 1 [mem 0x10007000-0x10007fff]: assigned vm # [ 0.051544] pci 0000:00:02.0: BAR 0 [io 0x1000-0x107f]: assigned vm # [ 0.051800] pci 0000:00:04.0: BAR 0 [io 0x1080-0x10bf]: assigned vm # [ 0.052057] pci 0000:00:07.0: BAR 0 [io 0x10c0-0x10ff]: assigned vm # [ 0.052326] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned vm # [ 0.052587] pci 0000:00:03.0: BAR 0 [io 0x1120-0x113f]: assigned vm # [ 0.052842] pci 0000:00:05.0: BAR 0 [io 0x1140-0x115f]: assigned vm # [ 0.053111] pci 0000:00:06.0: BAR 0 [io 0x1160-0x117f]: assigned vm # [ 0.053381] pci 0000:00:08.0: BAR 0 [io 0x1180-0x119f]: assigned vm # [ 0.053625] pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] vm # [ 0.053823] pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] vm # [ 0.054009] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] vm # [ 0.054403] iommu: Default domain type: Translated vm # [ 0.054576] iommu: DMA domain TLB invalidation policy: strict mode vm # [ 0.054780] pps_core: LinuxPPS API ver. 1 registered vm # [ 0.054913] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti vm # [ 0.055172] PTP clock support registered vm # [ 0.055279] EDAC MC: Ver: 3.0.0 vm # [ 0.055485] Registered efivars operations vm # [ 0.055772] NetLabel: Initializing vm # [ 0.055868] NetLabel: domain hash size = 128 vm # [ 0.055987] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO vm # [ 0.056148] NetLabel: unlabeled traffic allowed by default vm # [ 0.056371] vgaarb: loaded vm # [ 0.056554] clocksource: Switched to clocksource arch_sys_counter vm # [ 0.059943] VFS: Disk quotas dquot_6.6.0 vm # [ 0.060183] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) vm # [ 0.060825] AppArmor: AppArmor Filesystem Enabled vm # [ 0.060973] pnp: PnP ACPI init vm # [ 0.061125] system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved vm # [ 0.061451] pnp: PnP ACPI: found 1 devices vm # [ 0.066113] NET: Registered PF_INET protocol family vm # [ 0.066295] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) vm # [ 0.066724] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) vm # [ 0.067527] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) vm # [ 0.068077] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) vm # [ 0.068717] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) vm # [ 0.068947] TCP: Hash tables configured (established 8192 bind 8192) vm # [ 0.069149] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) vm # [ 0.069382] UDP hash table entries: 512 (order: 2, 16384 bytes, linear) vm # [ 0.069578] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) vm # [ 0.069786] NET: Registered PF_UNIX/PF_LOCAL protocol family vm # [ 0.069972] NET: Registered PF_XDP protocol family vm # [ 0.070125] PCI: CLS 0 bytes, default 64 vm # [ 0.070348] Trying to unpack rootfs image as initramfs... vm # [ 0.072704] kvm [1]: HYP mode not available vm # [ 0.073095] Initialise system trusted keyrings vm # [ 0.073330] Key type blacklist registered vm # [ 0.084833] workingset: timestamp_bits=42 max_order=18 bucket_order=0 vm # [ 0.085633] zbud: loaded vm # [ 0.085860] integrity: Platform Keyring initialized vm # [ 0.086024] integrity: Machine keyring initialized vm # [ 0.086158] Key type asymmetric registered vm # [ 0.086286] Asymmetric key parser 'x509' registered vm # [ 0.187937] Freeing initrd memory: 30468K vm # [ 0.190692] alg: self-tests for CTR-KDF (hmac(sha256)) passed vm # [ 0.191134] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) vm # [ 0.191796] io scheduler mq-deadline registered vm # [ 0.192837] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 vm # [ 0.193247] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 vm # [ 0.193524] ACPI: button: Power Button [PWRB] vm # [ 0.194291] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled vm # [ 0.194894] Serial: AMBA driver vm # [ 0.195144] SuperH (H)SCI(F) driver initialized vm # [ 0.195443] msm_serial: driver initialized vm # [ 0.196109] mousedev: PS/2 mouse device common for all mice vm # [ 0.196780] rtc-efi rtc-efi.0: registered as rtc0 vm # [ 0.197035] rtc-efi rtc-efi.0: setting system clock to 2026-10-13T11:56:08 UTC (1791892568) vm # [ 0.197694] ledtrig-cpu: registered to indicate activity on CPUs vm # [ 0.204348] NET: Registered PF_INET6 protocol family vm # [ 0.207480] Segment Routing with IPv6 vm # [ 0.207847] In-situ OAM (IOAM) with IPv6 vm # [ 0.208177] mip6: Mobile IPv6 vm # [ 0.208384] NET: Registered PF_PACKET protocol family vm # [ 0.208916] mpls_gso: MPLS GSO support vm # [ 0.209336] registered taskstats version 1 vm # [ 0.209546] Loading compiled-in X.509 certificates vm # [ 0.221603] Loaded X.509 cert 'Build time autogenerated kernel key: 10c55a12a481418dc388ae002ec2e41cc758f001' vm # [ 0.222266] zswap: loaded using pool lzo/zbud vm # [ 0.222677] Key type .fscrypt registered vm # [ 0.223012] Key type fscrypt-provisioning registered vm # [ 0.226176] Key type encrypted registered vm # [ 0.226364] AppArmor: AppArmor sha1 policy hashing enabled vm # [ 0.226887] ima: secureboot mode disabled vm # [ 0.227171] ima: No TPM chip found, activating TPM-bypass! vm # [ 0.227673] ima: Allocated hash algorithm: sha256 vm # [ 0.228039] ima: No architecture policies found vm # [ 0.228388] evm: Initialising EVM extended attributes: vm # [ 0.228807] evm: security.selinux vm # [ 0.228979] evm: security.SMACK64 (disabled) vm # [ 0.229196] evm: security.SMACK64EXEC (disabled) vm # [ 0.229421] evm: security.SMACK64TRANSMUTE (disabled) vm # [ 0.229675] evm: security.SMACK64MMAP (disabled) vm # [ 0.229915] evm: security.apparmor vm # [ 0.230082] evm: security.ima vm # [ 0.230262] evm: security.capability vm # [ 0.230454] evm: HMAC attrs: 0x1 vm # [ 0.266161] clk: Disabling unused clocks vm # [ 0.267171] Freeing unused kernel memory: 6464K vm # [ 0.268234] Checked W+X mappings: passed, no W+X pages found vm # [ 0.268410] Run /init as init process vm # Loading, please wait... vm # Starting systemd-udevd version 252.39-1~deb12u2 vm # [ 0.924532] ACPI: \_SB_.L001: Enabled at IRQ 36 vm # [ 0.924890] virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) vm # [ 0.925758] ACPI: \_SB_.L002: Enabled at IRQ 37 vm # [ 0.925996] virtio-pci 0000:00:02.0: enabling device (0005 -> 0007) vm # [ 0.927862] ACPI: \_SB_.L003: Enabled at IRQ 38 vm # [ 0.928112] virtio-pci 0000:00:03.0: enabling device (0005 -> 0007) vm # [ 0.929041] ACPI: \_SB_.L000: Enabled at IRQ 35 vm # [ 0.929288] virtio-pci 0000:00:04.0: enabling device (0005 -> 0007) vm # [ 0.930172] virtio-pci 0000:00:05.0: enabling device (0005 -> 0007) vm # [ 0.931731] virtio-pci 0000:00:06.0: enabling device (0005 -> 0007) vm # [ 0.932682] virtio-pci 0000:00:07.0: enabling device (0005 -> 0007) vm # [ 0.933585] virtio-pci 0000:00:08.0: enabling device (0005 -> 0007) vm # [ 0.940461] virtio_blk virtio1: 2/0/0 default/read/poll queues vm # [ 0.940977] virtio_blk virtio1: [vda] 6291456 512-byte logical blocks (3.22 GB/3.00 GiB) vm # [ 0.941981] vda: vda1 vda15 vm # [ 0.949001] virtio_net virtio2 enp0s3: renamed from eth0 vm # Begin: Loading essential drivers ... done. vm # Begin: Running /scripts/init-premount ... done. vm # Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done. vm # Begin: Running /scripts/local-premount ... done. vm # Warning: fsck not present, so skipping root file system vm # [ 1.173044] EXT4-fs (vda1): mounted filesystem with ordered data mode. Quota mode: none. vm # done. vm # Begin: Running /scripts/local-bottom ... GROWROOT: NOCHANGE: partition 1 could only be grown by 2015 [fudge=2048] vm # done. vm # Begin: Running /scripts/init-bottom ... done. vm # [ 1.198204] Not activating Mandatory Access Control as /sbin/tomoyo-init does not exist. vm # [ 1.237275] systemd[1]: Inserted module 'autofs4' vm # [ 1.254341] systemd[1]: systemd 252.39-1~deb12u2 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) vm # [ 1.255345] systemd[1]: Detected virtualization qemu. vm # [ 1.255487] systemd[1]: Detected architecture arm64. vm # vm # Welcome to Debian GNU/Linux 12 (bookworm)! vm # vm # [ 1.256763] systemd[1]: No hostname configured, using default hostname. vm # [ 1.256994] systemd[1]: Hostname set to . vm # [ 1.257685] systemd[1]: Initializing machine ID from random generator. vm # [ 1.257904] systemd[1]: Installed transient /etc/machine-id file. vm # [ 1.373111] systemd[1]: Condition check resulted in system.slice - System Slice being skipped. vm # [ 1.373522] systemd[1]: sshd.service: Cannot add dependency job, ignoring: Unit sshd.service failed to load properly, please adjust/correct and reload service manager: File exists vm # [ 1.374188] systemd[1]: Queued start job for default target graphical.target. vm # [ 1.432509] systemd[1]: Created slice system-getty.slice - Slice /system/getty. vm # [ OK ] Created slice system-getty.slice - Slice /system/getty. vm # [ 1.433220] systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. vm # [ OK ] Created slice system-modpr…lice - Slice /system/modprobe. vm # [ 1.433819] systemd[1]: Created slice system-systemd\x2dgrowfs.slice - Slice /system/systemd-growfs. vm # [ OK ] Created slice system-syste…0m - Slice /system/systemd-growfs. vm # [ 1.434440] systemd[1]: Created slice user.slice - User and Session Slice. vm # [ OK ] Created slice user.slice - User and Session Slice. vm # [ 1.434926] systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. vm # [ OK ] Started systemd-ask-passwo…quests to Console Directory Watch. vm # [ 1.435593] systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. vm # [ OK ] Started systemd-ask-passwo… Requests to Wall Directory Watch. vm # [ 1.436263] systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. vm # [ OK ] Set up automount proc-sys-…rmats File System Automount Point. vm # [ 1.437043] systemd[1]: Expecting device dev-disk-by\x2dpartuuid-f1e2968d\x2d2e95\x2d4a1f\x2d8395\x2d3ad38cf1b804.device - /dev/disk/by-partuuid/f1e2968d-2e95-4a1f-8395-3ad38cf1b804... vm # Expecting device dev-disk-…68d-2e95-4a1f-8395-3ad38cf1b804... vm # [ 1.437765] systemd[1]: Expecting device dev-hvc0.device - /dev/hvc0... vm # Expecting device dev-hvc0.device - /dev/hvc0... vm # [ 1.438153] systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... vm # Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... vm # [ 1.438590] systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. vm # [ OK ] Reached target cryptsetup.…get - Local Encrypted Volumes. vm # [ 1.439101] systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. vm # [ OK ] Reached target integrityse…Local Integrity Protected Volumes. vm # [ 1.439629] systemd[1]: Reached target paths.target - Path Units. vm # [ OK ] Reached target paths.target - Path Units. vm # [ 1.440038] systemd[1]: Reached target remote-fs.target - Remote File Systems. vm # [ OK ] Reached target remote-fs.target - Remote File Systems. vm # [ 1.440509] systemd[1]: Reached target slices.target - Slice Units. vm # [ OK ] Reached target slices.target - Slice Units. vm # [ 1.441022] systemd[1]: Reached target swap.target - Swaps. vm # [ OK ] Reached target swap.target - Swaps. vm # [ 1.441388] systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. vm # [ OK ] Reached target veritysetup… - Local Verity Protected Volumes. vm # [ 1.441961] systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket. vm # [ OK ] Listening on systemd-fsckd…sck to fsckd communication Socket. vm # [ 1.442504] systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. vm # [ OK ] Listening on systemd-initc… initctl Compatibility Named Pipe. vm # [ 1.443226] systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket. vm # [ OK ] Listening on systemd-journ…socket - Journal Audit Socket. vm # [ 1.444287] systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). vm # [ OK ] Listening on systemd-journ…t - Journal Socket (/dev/log). vm # [ 1.445037] systemd[1]: Listening on systemd-journald.socket - Journal Socket. vm # [ OK ] Listening on systemd-journald.socket - Journal Socket. vm # [ 1.445553] systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. vm # [ OK ] Listening on systemd-netwo… - Network Service Netlink Socket. vm # [ 1.446115] systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. vm # [ OK ] Listening on systemd-udevd….socket - udev Control Socket. vm # [ 1.446665] systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. vm # [ OK ] Listening on systemd-udevd…l.socket - udev Kernel Socket. vm # [ 1.447464] systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... vm # Mounting dev-hugepages.mount - Huge Pages File System... vm # [ 1.448226] systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... vm # Mounting dev-mqueue.mountPOSIX Message Queue File System... vm # [ 1.449272] systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... vm # Mounting sys-kernel-debug.… - Kernel Debug File System... vm # [ 1.450005] systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... vm # Mounting sys-kernel-tracin… - Kernel Trace File System... vm # [ 1.450921] systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... vm # Starting kmod-static-nodes…ate List of Static Device Nodes... vm # [ 1.451716] systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... vm # Starting modprobe@configfs…m - Load Kernel Module configfs... vm # [ 1.452491] systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... vm # Starting modprobe@dm_mod.s…[0m - Load Kernel Module dm_mod... vm # [ 1.453655] systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... vm # Starting modprobe@drm.service - Load Kernel Module drm... vm # [ 1.454698] systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... vm # Starting modprobe@efi_psto…- Load Kernel Module efi_pstore... vm # [ 1.455433] systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... vm # Starting modprobe@fuse.ser…e - Load Kernel Module fuse... vm # [ 1.456188] systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... vm # Starting modprobe@loop.ser…e - Load Kernel Module loop... vm # [ 1.457996] systemd[1]: netplan-ovs-cleanup.service - OpenVSwitch configuration for cleanup was skipped because of an unmet condition check (ConditionFileIsExecutable=/usr/bin/ovs-vsctl). vm # [ 1.459868] systemd[1]: Starting systemd-fsck-root.service - File System Check on Root Device... vm # Starting systemd-fsck-root…ile System Check on Root Device...[ 1.462355] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. vm # [ 1.462763] device-mapper: uevent: version 1.0.3 vm # [ 1.463159] device-mapper: ioctl: 4.47.0-ioctl (2022-07-28) initialised: dm-devel@redhat.com vm # vm # [ 1.465662] loop: module loaded vm # [ 1.467783] systemd[1]: Starting systemd-journald.service - Journal Service... vm # Starting systemd-journald.service - Journal Service... vm # [ 1.469903] ACPI: bus type drm_connector registered vm # [ 1.470232] systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... vm # Starting systemd-modules-l…rvice - Load Kernel Modules... vm # [ 1.471525] systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... vm # Starting systemd-network-g… units from Kernel command line...[ 1.472135] pstore: Using crash dump compression: deflate vm # vm # [ 1.473054] systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... vm # Starting systemd-udev-trig…[0m - Coldplug All udev Devices...[ 1.473781] fuse: init (API version 7.38) vm # vm # [ 1.474152] pstore: Registered efi as persistent store backend vm # [ 1.475148] systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. vm # [ OK ] Mounted dev-hugepages.mount - Huge Pages File System. vm # [ 1.476164] systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. vm # [ OK ] Mounted dev-mqueue.mount[…- POSIX Message Queue File System. vm # [ 1.477009] systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. vm # [ OK ] Mounted sys-kernel-debug.m…nt - Kernel Debug File System. vm # [ 1.477688] systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. vm # [ OK ] Mounted sys-kernel-tracing…nt - Kernel Trace File System. vm # [ 1.478276] systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. vm # [ OK ] Finished kmod-static-nodes…reate List of Static Device Nodes. vm # [ 1.479344] systemd[1]: modprobe@configfs.service: Deactivated successfully. vm # [ 1.479871] systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. vm # [ OK ] Finished modprobe@configfs…[0m - Load Kernel Module configfs. vm # [ 1.481369] systemd[1]: modprobe@dm_mod.service: Deactivated successfully. vm # [ 1.481870] systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. vm # [ OK ] Finished modprobe@dm_mod.s…e - Load Kernel Module dm_mod. vm # [ 1.482891] systemd[1]: modprobe@drm.service: Deactivated successfully. vm # [ 1.483399] systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. vm # [ OK ] Finished modprobe@drm.service - Load Kernel Module drm. vm # [ 1.484480] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. vm # [ 1.487120] systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. vm # [ OK ] Finished modprobe@efi_psto…m - Load Kernel Module efi_pstore. vm # [ 1.488335] systemd[1]: modprobe@fuse.service: Deactivated successfully. vm # [ 1.489061] systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. vm # [ OK ] Finished modprobe@fuse.service - Load Kernel Module fuse. vm # [ 1.489938] systemd[1]: modprobe@loop.service: Deactivated successfully. vm # [ 1.490818] systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. vm # [ OK ] Finished modprobe@loop.service - Load Kernel Module loop. vm # [ 1.492136] systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. vm # [ OK ] Finished systemd-network-g…rk units from Kernel command line. vm # [ 1.495667] systemd[1]: Finished systemd-fsck-root.service - File System Check on Root Device. vm # [ OK ] Finished systemd-fsck-root… File System Check on Root Device. vm # [ 1.500804] systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. vm # [ OK ] Finished systemd-modules-l…service - Load Kernel Modules. vm # [ 1.501714] systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... vm # Mounting sys-fs-fuse-conne… - FUSE Control File System... vm # [ 1.502447] systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... vm # Mounting sys-kernel-config…ernel Configuration File System... vm # [ 1.503224] systemd[1]: Started systemd-fsckd.service - File System Check Daemon to report status. vm # [ OK ] Started systemd-fsckd.serv…tem Check Daemon to report status. vm # [ 1.506003] systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... vm # Starting systemd-remount-f…nt Root and Kernel File Systems... vm # [ 1.506579] systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. vm # [ 1.507213] systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... vm # Starting systemd-sysctl.se…ce - Apply Kernel Variables... vm # [ 1.507841] systemd[1]: Started systemd-journald.service - Journal Service. vm # [ OK ] Started systemd-journald.service - Journal Service. vm # [ OK ] Finished systemd-udev-trig…e - Coldplug All udev Devices. vm # [ OK ] Mounted sys-fs-fuse-connec…nt - FUSE Control File System. vm # [ OK ] Mounted sys-kernel-config.… Kernel Configuration File System. vm # [ OK ] Finished systemd-sysctl.service - Apply Kernel Variables. vm # [ 1.516361] EXT4-fs (vda1): re-mounted. Quota mode: none. vm # [ OK ] Finished systemd-remount-f…ount Root and Kernel File Systems. vm # Starting cloud-init-local.…cloud-init job (pre-networking)... vm # Starting systemd-growfs@-.…ice - Grow File System on /... vm # Starting systemd-journal-f…h Journal to Persistent Storage... vm # Starting systemd-random-se…ice - Load/Save Random Seed... vm # Starting systemd-sysusers.…rvice - Create System Users... vm # [ 1.526672] EXT4-fs (vda1): resizing filesystem from 753408 to 753408 blocks vm # [ 1.527061] systemd-journald[216]: Received client request to flush runtime journal. vm # [ OK ] Finished systemd-random-se…rvice - Load/Save Random Seed. vm # [ OK ] Finished systemd-growfs@-.…rvice - Grow File System on /. vm # [ OK ] Finished systemd-journal-f…ush Journal to Persistent Storage. vm # [ OK ] Finished systemd-sysusers.service - Create System Users. vm # Starting systemd-tmpfiles-…ate Static Device Nodes in /dev... vm # [ OK ] Finished systemd-tmpfiles-…reate Static Device Nodes in /dev. vm # [ OK ] Reached target local-fs-pr…reparation for Local File Systems. vm # Starting systemd-udevd.ser…ger for Device Events and Files... vm # [ OK ] Started systemd-udevd.serv…nager for Device Events and Files. vm # [ OK ] Found device dev-ttyAMA0.device - /dev/ttyAMA0. vm # [ OK ] Found device dev-disk-by\x…2968d-2e95-4a1f-8395-3ad38cf1b804. vm # [ 1.651559] 9pnet: Installing 9P2000 support vm # [ OK ] Found device dev-hvc0.device - /dev/hvc0. vm # [ 1.741742] cloud-init[291]: Cloud-init v. 22.4.2 running 'init-local' at Tue, 13 Oct 2026 11:56:10 +0000. Up 1.73 seconds. vm # Mounting boot-efi.mount - /boot/efi... vm # [ OK ] Mounted boot-efi.mount - /boot/efi. vm # [ OK ] Reached target local-fs.target - Local File Systems. vm # Starting apparmor.service - Load AppArmor profiles... vm # Starting systemd-binfmt.se…et Up Additional Binary Formats... vm # Starting systemd-machine-i… a transient machine-id on disk... vm # Starting systemd-tmpfiles-…te System Files and Directories... vm # Mounting proc-sys-fs-binfm…utable File Formats File System... vm # [ OK ] Finished systemd-tmpfiles-…eate System Files and Directories. vm # Starting systemd-resolved.…e - Network Name Resolution... vm # Starting systemd-timesyncd… - Network Time Synchronization... vm # Starting systemd-update-ut…rd System Boot/Shutdown in UTMP... vm # [ OK ] Finished systemd-machine-i…it a transient machine-id on disk. vm # [ OK ] Mounted proc-sys-fs-binfmt…ecutable File Formats File System. vm # [ OK ] Finished systemd-binfmt.se… Set Up Additional Binary Formats. vm # [ OK ] Finished systemd-update-ut…cord System Boot/Shutdown in UTMP. vm # [ OK ] Started systemd-timesyncd.…0m - Network Time Synchronization. vm # [ OK ] Reached target time-set.target - System Time Set. vm # [ 2.681128] audit: type=1400 audit(1791892570.980:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lsb_release" pid=353 comm="apparmor_parser" vm # [ OK ] Started systemd-resolved.s…ice - Network Name Resolution. vm # [ OK ] Reached target nss-lookup.…m - Host and Network Name Lookups. vm # [ 2.716340] audit: type=1400 audit(1791892571.012:3): apparmor="STATUS" operation="profile_load" profile="unconfined" name="nvidia_modprobe" pid=354 comm="apparmor_parser" vm # [ 2.717338] audit: type=1400 audit(1791892571.016:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="nvidia_modprobe//kmod" pid=354 comm="apparmor_parser" vm # [ 2.774138] audit: type=1400 audit(1791892571.072:5): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/bin/man" pid=358 comm="apparmor_parser" vm # [ 2.775668] audit: type=1400 audit(1791892571.072:6): apparmor="STATUS" operation="profile_load" profile="unconfined" name="man_filter" pid=358 comm="apparmor_parser" vm # [ 2.777314] audit: type=1400 audit(1791892571.076:7): apparmor="STATUS" operation="profile_load" profile="unconfined" name="man_groff" pid=358 comm="apparmor_parser" vm # [ 2.810002] audit: type=1400 audit(1791892571.108:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=357 comm="apparmor_parser" vm # [ 2.810974] audit: type=1400 audit(1791892571.108:9): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/NetworkManager/nm-dhcp-helper" pid=357 comm="apparmor_parser" vm # [ 2.811865] audit: type=1400 audit(1791892571.108:10): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/connman/scripts/dhclient-script" pid=357 comm="apparmor_parser" vm # [ OK ] Finished apparmor.service - Load AppArmor profiles. vm # [ 11.933157] cloud-init[291]: 2026-10-13 11:56:20,240 - url_helper.py[ERROR]: Timed out, no response from urls: ['http://169.254.169.254/openstack'] vm # [ 11.938060] cloud-init[291]: 2026-10-13 11:56:20,245 - util.py[WARNING]: No active metadata service found vm # [ OK ] Finished cloud-init-local.…l cloud-init job (pre-networking). vm # [ OK ] Reached target network-pre…get - Preparation for Network. vm # Starting systemd-networkd.…ice - Network Configuration... vm # [ OK ] Listening on systemd-rfkil…l Switch Status /dev/rfkill Watch. vm # [ 12.318101] cfg80211: Loading compiled-in X.509 certificates for regulatory database vm # [ 12.318648] cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' vm # [ 12.321008] cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' vm # [ 12.321781] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' vm # [ 12.322260] cfg80211: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' vm # [ 12.322688] platform regulatory.0: firmware: failed to load regulatory.db (-2) vm # [ 12.323145] firmware_class: See https://wiki.debian.org/Firmware for information about missing firmware vm # [ 12.323746] platform regulatory.0: firmware: failed to load regulatory.db (-2) vm # [ 12.324078] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 vm # [ 12.324519] cfg80211: failed to load regulatory.db vm # [ OK ] Started systemd-networkd.service - Network Configuration. vm # [ OK ] Reached target network.target - Network. vm # Starting systemd-networkd-…it for Network to be Configured... vm # [ OK ] Finished systemd-networkd-…Wait for Network to be Configured. vm # Starting cloud-init.servic… job (metadata service crawler)... vm # [ 13.759544] cloud-init[391]: Cloud-init v. 22.4.2 running 'init' at Tue, 13 Oct 2026 11:56:22 +0000. Up 13.74 seconds. vm # [ 13.766882] cloud-init[391]: ci-info: ++++++++++++++++++++++++++++++++++++++Net device info++++++++++++++++++++++++++++++++++++++ vm # [ 13.767545] cloud-init[391]: ci-info: +--------+------+----------------------------+---------------+--------+-------------------+ vm # [ 13.768354] cloud-init[391]: ci-info: | Device | Up | Address | Mask | Scope | Hw-Address | vm # [ 13.769000] cloud-init[391]: ci-info: +--------+------+----------------------------+---------------+--------+-------------------+ vm # [ 13.769672] cloud-init[391]: ci-info: | enp0s3 | True | 10.0.2.15 | 255.255.255.0 | global | 52:54:00:12:34:56 | vm # [ 13.770318] cloud-init[391]: ci-info: | enp0s3 | True | fe80::5054:ff:fe12:3456/64 | . | link | 52:54:00:12:34:56 | vm # [ 13.770972] cloud-init[391]: ci-info: | lo | True | 127.0.0.1 | 255.0.0.0 | host | . | vm # [ 13.771639] cloud-init[391]: ci-info: | lo | True | ::1/128 | . | host | . | vm # [ 13.772515] cloud-init[391]: ci-info: +--------+------+----------------------------+---------------+--------+-------------------+ vm # [ 13.773154] cloud-init[391]: ci-info: ++++++++++++++++++++++++++++Route IPv4 info+++++++++++++++++++++++++++++ vm # [ 13.773703] cloud-init[391]: ci-info: +-------+-------------+----------+-----------------+-----------+-------+ vm # [ 13.774291] cloud-init[391]: ci-info: | Route | Destination | Gateway | Genmask | Interface | Flags | vm # [ 13.774839] cloud-init[391]: ci-info: +-------+-------------+----------+-----------------+-----------+-------+ vm # [ 13.775383] cloud-init[391]: ci-info: | 0 | 0.0.0.0 | 10.0.2.2 | 0.0.0.0 | enp0s3 | UG | vm # [ 13.775933] cloud-init[391]: ci-info: | 1 | 10.0.2.0 | 0.0.0.0 | 255.255.255.0 | enp0s3 | U | vm # [ 13.776699] cloud-init[391]: ci-info: | 2 | 10.0.2.2 | 0.0.0.0 | 255.255.255.255 | enp0s3 | UH | vm # [ 13.777233] cloud-init[391]: ci-info: | 3 | 10.0.2.3 | 0.0.0.0 | 255.255.255.255 | enp0s3 | UH | vm # [ 13.777810] cloud-init[391]: ci-info: +-------+-------------+----------+-----------------+-----------+-------+ vm # [ 13.778365] cloud-init[391]: ci-info: +++++++++++++++++++Route IPv6 info+++++++++++++++++++ vm # [ 13.778866] cloud-init[391]: ci-info: +-------+-------------+---------+-----------+-------+ vm # [ 13.779371] cloud-init[391]: ci-info: | Route | Destination | Gateway | Interface | Flags | vm # [ 13.779857] cloud-init[391]: ci-info: +-------+-------------+---------+-----------+-------+ vm # [ 13.780486] cloud-init[391]: ci-info: | 0 | fe80::/64 | :: | enp0s3 | U | vm # [ 13.780962] cloud-init[391]: ci-info: | 2 | local | :: | enp0s3 | U | vm # [ 13.781512] cloud-init[391]: ci-info: | 3 | multicast | :: | enp0s3 | U | vm # [ 13.781965] cloud-init[391]: ci-info: +-------+-------------+---------+-----------+-------+ vm # [ 13.820913] cloud-init[391]: 2026-10-13 11:56:22,128 - url_helper.py[ERROR]: Timed out, no response from urls: ['http://169.254.169.254/openstack'] vm # [ 13.821694] cloud-init[391]: 2026-10-13 11:56:22,128 - util.py[WARNING]: No active metadata service found vm # [ 13.832281] cloud-init[391]: 2026-10-13 11:56:22,138 - url_helper.py[WARNING]: Calling 'None' failed [0/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 101] Network is unreachable'))] vm # [ 13.835347] cloud-init[391]: 2026-10-13 11:56:22,139 - url_helper.py[WARNING]: Calling 'None' failed [0/120s]: request error [HTTPConnectionPool(host='fd00:ec2::254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 101] Network is unreachable'))] vm # [ 14.865331] cloud-init[391]: 2026-10-13 11:56:23,172 - url_helper.py[WARNING]: Calling 'None' failed [1/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 101] Network is unreachable'))] vm # [ 14.866901] cloud-init[391]: 2026-10-13 11:56:23,173 - url_helper.py[WARNING]: Calling 'None' failed [1/120s]: request error [HTTPConnectionPool(host='fd00:ec2::254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 101] Network is unreachable'))] vm # [ 15.868432] cloud-init[391]: 2026-10-13 11:56:24,175 - url_helper.py[WARNING]: Calling 'None' failed [2/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 101] Network is unreachable'))] vm # [ 15.870455] cloud-init[391]: 2026-10-13 11:56:24,177 - url_helper.py[WARNING]: Calling 'None' failed [2/120s]: request error [HTTPConnectionPool(host='fd00:ec2::254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 113] No route to host'))] vm # [ 16.903937] cloud-init[391]: 2026-10-13 11:56:25,206 - url_helper.py[WARNING]: Calling 'None' failed [3/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 101] Network is unreachable'))] vm # [ 16.905442] cloud-init[391]: 2026-10-13 11:56:25,212 - url_helper.py[WARNING]: Calling 'None' failed [3/120s]: request error [HTTPConnectionPool(host='fd00:ec2::254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 113] No route to host'))] vm # [ 17.922331] cloud-init[391]: 2026-10-13 11:56:26,228 - url_helper.py[WARNING]: Calling 'None' failed [4/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 101] Network is unreachable'))] vm # [ 17.924981] cloud-init[391]: 2026-10-13 11:56:26,230 - url_helper.py[WARNING]: Calling 'None' failed [4/120s]: request error [HTTPConnectionPool(host='fd00:ec2::254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 113] No route to host'))] vm # [ 18.929546] cloud-init[391]: 2026-10-13 11:56:27,236 - url_helper.py[WARNING]: Calling 'None' failed [5/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 101] Network is unreachable'))] vm # [ 18.932938] cloud-init[391]: 2026-10-13 11:56:27,240 - url_helper.py[WARNING]: Calling 'None' failed [5/120s]: request error [HTTPConnectionPool(host='fd00:ec2::254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 113] No route to host'))] vm # [ 20.985700] cloud-init[391]: 2026-10-13 11:56:29,292 - url_helper.py[WARNING]: Calling 'None' failed [7/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 101] Network is unreachable'))] vm # [ 20.988469] cloud-init[391]: 2026-10-13 11:56:29,293 - url_helper.py[WARNING]: Calling 'None' failed [7/120s]: request error [HTTPConnectionPool(host='fd00:ec2::254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 113] No route to host'))] vm # [ 22.991454] cloud-init[391]: 2026-10-13 11:56:31,298 - url_helper.py[WARNING]: Calling 'None' failed [9/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 101] Network is unreachable'))] vm # [ 22.993228] cloud-init[391]: 2026-10-13 11:56:31,299 - url_helper.py[WARNING]: Calling 'None' failed [9/120s]: request error [HTTPConnectionPool(host='fd00:ec2::254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 113] No route to host'))] vm # [ 25.034113] cloud-init[391]: 2026-10-13 11:56:33,341 - url_helper.py[WARNING]: Calling 'None' failed [11/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 101] Network is unreachable'))] vm # [ 25.036251] cloud-init[391]: 2026-10-13 11:56:33,343 - url_helper.py[WARNING]: Calling 'None' failed [11/120s]: request error [HTTPConnectionPool(host='fd00:ec2::254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 113] No route to host'))] vm # [ 102.047058] cloud-init[391]: 2026-10-13 11:57:50,354 - url_helper.py[WARNING]: Calling 'None' failed [88/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 102.048643] cloud-init[391]: 2026-10-13 11:57:50,356 - url_helper.py[WARNING]: Calling 'None' failed [88/120s]: request error [HTTPConnectionPool(host='fd00:ec2::254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 113] No route to host'))] vm # [ 133.113581] cloud-init[391]: 2026-10-13 11:58:21,420 - url_helper.py[WARNING]: Calling 'None' failed [119/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by ConnectTimeoutError(, 'Connection to 169.254.169.254 timed out. (connect timeout=29.0)'))] vm # [ 133.116160] cloud-init[391]: 2026-10-13 11:58:21,421 - url_helper.py[WARNING]: Calling 'None' failed [119/120s]: unexpected error [Attempted to set connect timeout to 0.0, but the timeout cannot be set to a value less than or equal to 0.] vm # [ 136.202616] cloud-init[391]: 2026-10-13 11:58:24,509 - url_helper.py[ERROR]: Timed out, no response from urls: ['http://169.254.169.254/2009-04-04/meta-data/instance-id', 'http://[fd00:ec2::254]/2009-04-04/meta-data/instance-id'] vm # [ 136.204045] cloud-init[391]: 2026-10-13 11:58:24,509 - DataSourceEc2.py[CRITICAL]: Giving up on md from ['http://169.254.169.254/2009-04-04/meta-data/instance-id', 'http://[fd00:ec2::254]/2009-04-04/meta-data/instance-id'] after 122 seconds vm # [ 136.209214] cloud-init[391]: 2026-10-13 11:58:24,516 - url_helper.py[WARNING]: Calling 'None' failed [0/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 137.307418] cloud-init[391]: 2026-10-13 11:58:25,614 - url_helper.py[WARNING]: Calling 'None' failed [1/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 138.355374] cloud-init[391]: 2026-10-13 11:58:26,662 - url_helper.py[WARNING]: Calling 'None' failed [2/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 139.356323] cloud-init[391]: 2026-10-13 11:58:27,663 - url_helper.py[WARNING]: Calling 'None' failed [3/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 140.372518] cloud-init[391]: 2026-10-13 11:58:28,679 - url_helper.py[WARNING]: Calling 'None' failed [4/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 141.427223] cloud-init[391]: 2026-10-13 11:58:29,734 - url_helper.py[WARNING]: Calling 'None' failed [5/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 143.486296] cloud-init[391]: 2026-10-13 11:58:31,793 - url_helper.py[WARNING]: Calling 'None' failed [7/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 145.617964] cloud-init[391]: 2026-10-13 11:58:33,925 - url_helper.py[WARNING]: Calling 'None' failed [9/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 147.708285] cloud-init[391]: 2026-10-13 11:58:36,015 - url_helper.py[WARNING]: Calling 'None' failed [11/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 149.762351] cloud-init[391]: 2026-10-13 11:58:38,069 - url_helper.py[WARNING]: Calling 'None' failed [13/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 151.910178] cloud-init[391]: 2026-10-13 11:58:40,217 - url_helper.py[WARNING]: Calling 'None' failed [15/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 154.958811] cloud-init[391]: 2026-10-13 11:58:43,266 - url_helper.py[WARNING]: Calling 'None' failed [18/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 157.963626] cloud-init[391]: 2026-10-13 11:58:46,270 - url_helper.py[WARNING]: Calling 'None' failed [21/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 160.994935] cloud-init[391]: 2026-10-13 11:58:49,302 - url_helper.py[WARNING]: Calling 'None' failed [24/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 164.016701] cloud-init[391]: 2026-10-13 11:58:52,324 - url_helper.py[WARNING]: Calling 'None' failed [27/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 167.070187] cloud-init[391]: 2026-10-13 11:58:55,377 - url_helper.py[WARNING]: Calling 'None' failed [30/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 171.080689] cloud-init[391]: 2026-10-13 11:58:59,387 - url_helper.py[WARNING]: Calling 'None' failed [34/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 175.134387] cloud-init[391]: 2026-10-13 11:59:03,441 - url_helper.py[WARNING]: Calling 'None' failed [38/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 179.249210] cloud-init[391]: 2026-10-13 11:59:07,556 - url_helper.py[WARNING]: Calling 'None' failed [43/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 183.265175] cloud-init[391]: 2026-10-13 11:59:11,569 - url_helper.py[WARNING]: Calling 'None' failed [47/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 187.388092] cloud-init[391]: 2026-10-13 11:59:15,695 - url_helper.py[WARNING]: Calling 'None' failed [51/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 192.430520] cloud-init[391]: 2026-10-13 11:59:20,737 - url_helper.py[WARNING]: Calling 'None' failed [56/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 197.470315] cloud-init[391]: 2026-10-13 11:59:25,777 - url_helper.py[WARNING]: Calling 'None' failed [61/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 202.473229] cloud-init[391]: 2026-10-13 11:59:30,780 - url_helper.py[WARNING]: Calling 'None' failed [66/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 207.510088] cloud-init[391]: 2026-10-13 11:59:35,817 - url_helper.py[WARNING]: Calling 'None' failed [71/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 212.619953] cloud-init[391]: 2026-10-13 11:59:40,927 - url_helper.py[WARNING]: Calling 'None' failed [76/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 218.624671] cloud-init[391]: 2026-10-13 11:59:46,931 - url_helper.py[WARNING]: Calling 'None' failed [82/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 224.707493] cloud-init[391]: 2026-10-13 11:59:53,014 - url_helper.py[WARNING]: Calling 'None' failed [88/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 230.854704] cloud-init[391]: 2026-10-13 11:59:59,162 - url_helper.py[WARNING]: Calling 'None' failed [94/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 236.943067] cloud-init[391]: 2026-10-13 12:00:05,250 - url_helper.py[WARNING]: Calling 'None' failed [100/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 243.060869] cloud-init[391]: 2026-10-13 12:00:11,368 - url_helper.py[WARNING]: Calling 'None' failed [106/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 250.068935] cloud-init[391]: 2026-10-13 12:00:18,376 - url_helper.py[WARNING]: Calling 'None' failed [113/120s]: request error [HTTPConnectionPool(host='10.0.2.2', port=80): Max retries exceeded with url: /latest/meta-data/instance-id (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused'))] vm # [ 257.109372] cloud-init[391]: 2026-10-13 12:00:25,416 - url_helper.py[ERROR]: Timed out, no response from urls: ['http://10.0.2.2/latest/meta-data/instance-id'] vm # [ 257.112063] cloud-init[391]: 2026-10-13 12:00:25,416 - DataSourceCloudStack.py[CRITICAL]: Giving up on waiting for the metadata from ['http://10.0.2.2/latest/meta-data/instance-id'] after 120 seconds vm # [ OK ] Stopped systemd-networkd-w…Wait for Network to be Configured. vm # Stopping systemd-networkd-…it for Network to be Configured... vm # Stopping systemd-networkd.…ice - Network Configuration... vm # [ OK ] Stopped systemd-networkd.service - Network Configuration. vm # Starting systemd-networkd.…ice - Network Configuration... vm # [ OK ] Started systemd-networkd.service - Network Configuration. vm # Starting systemd-networkd-…it for Network to be Configured... vm # [ 257.554220] cloud-init[391]: 2026-10-13 12:00:25,861 - activators.py[WARNING]: Running ['netplan', 'apply'] resulted in stderr output: vm # [ 257.554984] cloud-init[391]: ** (generate:424): WARNING **: 12:00:25.526: Permissions for /etc/netplan/50-cloud-init.yaml are too open. Netplan configuration should NOT be accessible by others. vm # [ 257.555475] cloud-init[391]: Cannot call openvswitch: ovsdb-server.service is not running. vm # [ 257.555735] cloud-init[391]: ** (process:422): WARNING **: 12:00:25.666: Permissions for /etc/netplan/50-cloud-init.yaml are too open. Netplan configuration should NOT be accessible by others. vm # [ 257.556554] cloud-init[391]: ** (process:422): WARNING **: 12:00:25.736: Permissions for /etc/netplan/50-cloud-init.yaml are too open. Netplan configuration should NOT be accessible by others. vm # [ 257.557059] cloud-init[391]: ** (process:422): WARNING **: 12:00:25.736: Permissions for /etc/netplan/50-cloud-init.yaml are too open. Netplan configuration should NOT be accessible by others. vm # [ 257.557595] cloud-init[391]: Failed to connect system bus: No such file or directory vm # [ 257.557840] cloud-init[391]: Falling back to a hard restart of systemd-networkd.service vm # [ OK ] Finished systemd-networkd-…Wait for Network to be Configured. vm # [ 257.568803] cloud-init[391]: 2026-10-13 12:00:25,874 - warnings.py[WARNING]: ************************************************************************** vm # [ 257.569588] cloud-init[391]: # A new feature in cloud-init identified possible datasources for # vm # [ 257.569879] cloud-init[391]: # this system as: # vm # [ 257.570153] cloud-init[391]: # [] # vm # [ 257.570691] cloud-init[391]: # However, the datasource used was: None # vm # [ 257.571297] cloud-init[391]: # # vm # [ 257.571549] cloud-init[391]: # In the future, cloud-init will only attempt to use datasources that # vm # [ 257.571944] cloud-init[391]: # are identified or specifically configured. # vm # [ 257.572453] cloud-init[391]: # For more information see # vm # [ 257.572745] cloud-init[391]: # https://bugs.launchpad.net/bugs/1669675 # vm # [ 257.573073] cloud-init[391]: # # vm # [ 257.573574] cloud-init[391]: # If you are seeing this message, please file a bug against # vm # [ 257.573943] cloud-init[391]: # cloud-init at # vm # [ 257.574219] cloud-init[391]: # https://bugs.launchpad.net/cloud-init/+filebug?field.tags=dsid # vm # [ 257.574500] cloud-init[391]: # Make sure to include the cloud provider your instance is # vm # [ 257.574796] cloud-init[391]: # running on. # vm # [ 257.575124] cloud-init[391]: # # vm # [ 257.575665] cloud-init[391]: # After you have filed a bug, you can disable this warning by launching # vm # [ 257.575966] cloud-init[391]: # your instance with the cloud-config below, or putting that content # vm # [ 257.576471] cloud-init[391]: # into /etc/cloud/cloud.cfg.d/99-warnings.cfg # vm # [ 257.576749] cloud-init[391]: # # vm # [ 257.577034] cloud-init[391]: # #cloud-config # vm # [ 257.577323] cloud-init[391]: # warnings: # vm # [ 257.577716] cloud-init[391]: # dsid_missing_source: off # vm # [ 257.577967] cloud-init[391]: ************************************************************************** vm # [ 258.174563] cloud-init[391]: Generating public/private rsa key pair. vm # [ 258.174803] cloud-init[391]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key vm # [ 258.175060] cloud-init[391]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub vm # [ 258.175312] cloud-init[391]: The key fingerprint is: vm # [ 258.175465] cloud-init[391]: SHA256:q+5bhFBySVlF5j+kK3g3TtjOL6qhP36OupQo6LMqUbg root@localhost vm # [ 258.175723] cloud-init[391]: The key's randomart image is: vm # [ 258.175895] cloud-init[391]: +---[RSA 3072]----+ vm # [ 258.176151] cloud-init[391]: | ..++.o+ | vm # [ 258.176321] cloud-init[391]: | +o o | vm # [ 258.176470] cloud-init[391]: | . . . . | vm # [ 258.176623] cloud-init[391]: |. . . . + | vm # [ 258.176766] cloud-init[391]: | o . S . o | vm # [ 258.176908] cloud-init[391]: |E . . o + . . | vm # [ 258.177050] cloud-init[391]: |o.. o o * * | vm # [ 258.177191] cloud-init[391]: |oo . ..*.B.. | vm # [ 258.177330] cloud-init[391]: |+oo +BX*o.+o. | vm # [ 258.177469] cloud-init[391]: +----[SHA256]-----+ vm # [ 258.177620] cloud-init[391]: Generating public/private dsa key pair. vm # [ 258.177806] cloud-init[391]: Your identification has been saved in /etc/ssh/ssh_host_dsa_key vm # [ 258.178048] cloud-init[391]: Your public key has been saved in /etc/ssh/ssh_host_dsa_key.pub vm # [ 258.178297] cloud-init[391]: The key fingerprint is: vm # [ 258.178450] cloud-init[391]: SHA256:0Xd85qvcQki6JjVQPD0fCAyRYKSiP07j0Gb8OiElvMc root@localhost vm # [ 258.178696] cloud-init[391]: The key's randomart image is: vm # [ 258.178889] cloud-init[391]: +---[DSA 1024]----+ vm # [ 258.179041] cloud-init[391]: | .+.o*.o . | vm # [ 258.179190] cloud-init[391]: | o . * + o | vm # [ 258.179344] cloud-init[391]: |.. . o o + + o| vm # [ 258.179489] cloud-init[391]: |o.o . . o o + | vm # [ 258.179648] cloud-init[391]: |.oo S o . .| vm # [ 258.179798] cloud-init[391]: |.=.E + . . .| vm # [ 258.179942] cloud-init[391]: |..@. . o . . | vm # [ 258.180129] cloud-init[391]: | B.+ . o ..o | vm # [ 258.180272] cloud-init[391]: | +o. o o.. | vm # [ 258.180407] cloud-init[391]: +----[SHA256]-----+ vm # [ 258.180551] cloud-init[391]: Generating public/private ecdsa key pair. vm # [ 258.180750] cloud-init[391]: Your identification has been saved in /etc/ssh/ssh_host_ecdsa_key vm # [ 258.181019] cloud-init[391]: Your public key has been saved in /etc/ssh/ssh_host_ecdsa_key.pub vm # [ 258.181285] cloud-init[391]: The key fingerprint is: vm # [ 258.181443] cloud-init[391]: SHA256:R1jy47eX5MUy0QQoHgZiL1GyDeEebue5fGwnLPkAEBE root@localhost vm # [ 258.181698] cloud-init[391]: The key's randomart image is: vm # [ 258.181873] cloud-init[391]: +---[ECDSA 256]---+ vm # [ 258.182017] cloud-init[391]: | EoBo+.. ....| vm # [ 258.182172] cloud-init[391]: | + O =+ . o | vm # [ 258.182324] cloud-init[391]: | . = +o+o . .| vm # [ 258.182472] cloud-init[391]: | + o o.. o | vm # [ 258.182618] cloud-init[391]: | = S o . + o| vm # [ 258.182781] cloud-init[391]: | . + o . + = | vm # [ 258.182925] cloud-init[391]: | ++ . + | vm # [ 258.183085] cloud-init[391]: | .oo* .. | vm # [ 258.183230] cloud-init[391]: | o=.o | vm # [ 258.183372] cloud-init[391]: +----[SHA256]-----+ vm # [ 258.183519] cloud-init[391]: Generating public/private ed25519 key pair. vm # [ 258.183722] cloud-init[391]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key vm # [ 258.183994] cloud-init[391]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub vm # [ 258.184345] cloud-init[391]: The key fingerprint is: vm # [ 258.184508] cloud-init[391]: SHA256:yC62RuPu37Gmu6XreYqac39mDJNBlB1UT6olzY0sNIE root@localhost vm # [ 258.184767] cloud-init[391]: The key's randomart image is: vm # [ 258.184929] cloud-init[391]: +--[ED25519 256]--+ vm # [ 258.185077] cloud-init[391]: | ..=*+. . | vm # [ 258.185226] cloud-init[391]: | E..= * | vm # [ 258.185370] cloud-init[391]: | . o B o | vm # [ 258.185544] cloud-init[391]: | ... = | vm # [ 258.185689] cloud-init[391]: | ooS | vm # [ 258.185839] cloud-init[391]: | o.+ | vm # [ 258.185994] cloud-init[391]: | oo..+o | vm # [ 258.186137] cloud-init[391]: | .o++ =Bo | vm # [ 258.186276] cloud-init[391]: | oB=+%%o | vm # [ 258.186413] cloud-init[391]: +----[SHA256]-----+ vm # [ OK ] Finished cloud-init.servic…it job (metadata service crawler). vm # [ OK ] Reached target cloud-confi…t - Cloud-config availability. vm # [ OK ] Reached target network-online.target - Network is Online. vm # [ OK ] Reached target sysinit.target - System Initialization. vm # [ OK ] Started apt-daily.timer - Daily apt download activities. vm # [ OK ] Started apt-daily-upgrade.… apt upgrade and clean activities. vm # [ OK ] Started dpkg-db-backup.tim… Daily dpkg database backup timer. vm # [ OK ] Started e2scrub_all.timeretadata Check for All Filesystems. vm # [ OK ] Started fstrim.timer - Discard unused blocks once a week. vm # [ OK ] Started man-db.timer - Daily man-db regeneration. vm # [ OK ] Started systemd-tmpfiles-c… Cleanup of Temporary Directories. vm # [ OK ] Reached target timers.target - Timer Units. vm # [ OK ] Listening on cloud-init-ho… - cloud-init hotplug hook socket. vm # [ OK ] Listening on dbus.socket[…- D-Bus System Message Bus Socket. vm # [ OK ] Listening on uuidd.socketm - UUID daemon activation socket. vm # [ OK ] Reached target sockets.target - Socket Units. vm # [ OK ] Reached target basic.target - Basic System. vm # Starting cloud-config.serv…tings specified in cloud-config... vm # Starting dbus.service - D-Bus System Message Bus... vm # Starting e2scrub_reap.serv…e ext4 Metadata Check Snapshots... vm # Starting mount-store.service... vm # Starting systemd-logind.se…ice - User Login Management... vm # Starting systemd-user-sess…vice - Permit User Sessions... vm # [ OK ] Finished systemd-user-sess…ervice - Permit User Sessions. vm # [ OK ] Started getty@tty1.service - Getty on tty1. vm # [ OK ] Reached target getty.target - Login Prompts. vm # [ OK ] Started dbus.service - D-Bus System Message Bus. vm # [ OK ] Finished e2scrub_reap.serv…ine ext4 Metadata Check Snapshots. vm # [ 258.360221] FS-Cache: Loaded vm # [ 258.362361] 9p: Installing v9fs 9p2000 file system support vm # [ 258.362512] 9pnet: Limiting 'msize' to 512000 as this is the maximum supported by transport virtio vm # [ OK ] Started systemd-logind.service - User Login Management. vm # [ OK ] Started unattended-upgrade…0m - Unattended Upgrades Shutdown. vm # [ OK ] Finished mount-store.service. vm # [ OK ] Started backdoor.service. vm # [ OK ] Reached target multi-user.target - Multi-User System. vm # [ OK ] Reached target graphical.target - Graphical Interface. vm # Starting systemd-update-ut… Record Runlevel Change in UTMP... vm # [ OK ] Finished systemd-update-ut… - Record Runlevel Change in UTMP. vm # connecting to host... vm: Guest shell says: b'Spawning backdoor root shell...\n' vm: connected to guest root shell vm: (connecting took 264.29 seconds) vm: (finished: waiting for the VM to finish booting, in 264.82 seconds) vm: (finished: waiting for unit multi-user.target, in 264.83 seconds) (finished: run the VM test script, in 264.83 seconds) test script finished in 264.83s cleanup kill QemuMachine (pid 43889) vm # qemu-system-aarch64: terminating on signal 15 from pid 43828 () (finished: cleanup, in 0.01 seconds)