$ /nix/store/9ypz3flqsrl5xl495mm8h645gadjsxi1-coreutils-9.11/bin/timeout --kill-after=15s 7200s /nix/store/23zk8sgzamrbmj1fday15szf7f2279s8-nix-2.34.7/bin/nix --extra-experimental-features nix-command --extra-experimental-features flakes --log-format internal-json build --no-link --print-out-paths git+https://github.com/NorfairKing/centjes?ref=refs%2Fpull%2F8%2Fmerge&rev=0861c484ed0514efe3484a4f326b102a38615833&shallow=1#checks.x86_64-linux.e2e-test --print-build-logs[m
[33m[35;1mwarning:[0m ignoring untrusted flake configuration setting 'extra-substituters'.
Pass '--accept-flake-config' to trust it[m
[33m[35;1mwarning:[0m ignoring untrusted flake configuration setting 'extra-trusted-public-keys'.
Pass '--accept-flake-config' to trust it[m
[2m[m
[32mDownloading cached nixos-test-driver-centjes-e2e-test from https://cache.staging.nix-ci.com[m
[32mDownloaded cached nixos-test-driver-centjes-e2e-test (1888 B) in 692ms[m
[2mProgress: 0 of 2 built, 1 of 1 downloaded from cache[m
[1;34mBuilding /nix/store/7r8ws66ysvg4i52nif1hdkwsgig4nd75-vm-test-run-centjes-e2e-test.drv[m
[2m[vm-test-run-centjes-e2e-test] [mMachine state will be reset. To keep it, pass --keep-vm-state
[2m[vm-test-run-centjes-e2e-test] [m[1m[32mstart all VLans[0m
[2m[vm-test-run-centjes-e2e-test] [mstart vlan
[2m[vm-test-run-centjes-e2e-test] [mrunning vlan (pid 7; ctl /build/vde1.ctl)
[2m[vm-test-run-centjes-e2e-test] [m(finished: start all VLans, in 0.00 seconds)
[2m[vm-test-run-centjes-e2e-test] [mTest will time out and terminate in 3600 seconds
[2m[vm-test-run-centjes-e2e-test] [m[1m[32mrun the VM test script[0m
[2m[vm-test-run-centjes-e2e-test] [madditionally exposed symbols:
[2m[vm-test-run-centjes-e2e-test] [m    client, docsserver,
[2m[vm-test-run-centjes-e2e-test] [m    vlan1,
[2m[vm-test-run-centjes-e2e-test] [m    start_all, test_script, machines, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, Machine, t, debug
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: starting vm
[2m[vm-test-run-centjes-e2e-test] [mmke2fs 1.47.3 (8-Jul-2025)
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Disk image does not exist, creating the virtualisation disk image...[0m
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: QEMU running (pid 9)
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Formatting '/build/vm-state-docsserver/tmp.7nWBjeNuE4', fmt=raw size=1073741824[0m
[2m[vm-test-run-centjes-e2e-test] [mclient: starting vm
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Discarding device blocks:      0/262144             done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Creating filesystem with 262144 4k blocks and 65536 inodes[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Filesystem UUID: 8bafdc3e-a9c6-4182-85ff-ada38abb7a0a[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Superblock backups stored on blocks:[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # 	32768, 98304, 163840, 229376[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Allocating group tables: 0/8   done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Writing inode tables: 0/8   done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Creating journal (8192 blocks): done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Writing superblocks and filesystem accounting information: 0/8   done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Virtualisation disk image created.[0m
[2m[vm-test-run-centjes-e2e-test] [mmke2fs 1.47.3 (8-Jul-2025)
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # c[?7l[2J[0mSeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Disk image does not exist, creating the virtualisation disk image...[0m
[2m[vm-test-run-centjes-e2e-test] [mclient: QEMU running (pid 31)
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Formatting '/build/vm-state-client/tmp.1YIJzmJuwW', fmt=raw size=1073741824[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Discarding device blocks:      0/262144             done[0m
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: [1m[32mwaiting for unit default.target[0m
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: [1m[32mwaiting for the VM to finish booting[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Creating filesystem with 262144 4k blocks and 65536 inodes[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Filesystem UUID: 13ea7290-ac56-4d64-9292-a47a8c0330df[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Superblock backups stored on blocks:[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # 	32768, 98304, 163840, 229376[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Allocating group tables: 0/8   done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Writing inode tables: 0/8   done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Creating journal (8192 blocks): done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Writing superblocks and filesystem accounting information: 0/8   done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Virtualisation disk image created.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+3EFD1920+3EF31920 CA00[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Press Ctrl-B to configure iPXE (PCI 00:03.0)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM 3EFD1920 3EF31920 CB00[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Press Ctrl-B to configure iPXE (PCI 00:09.0)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Booting from ROM...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # c[?7l[2J[0mSeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Probing EDD (edd=off to disable)... ok[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+3EFD1920+3EF31920 CA00[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Press Ctrl-B to configure iPXE (PCI 00:03.0)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM 3EFD1920 3EF31920 CB00[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Press Ctrl-B to configure iPXE (PCI 00:09.0)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Booting from ROM...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # c[?7l[2J[    0.000000] Linux version 6.12.62 (nixbld@localhost) (gcc (GCC) 14.3.0, GNU ld (GNU Binutils) 2.44) #1-NixOS SMP PREEMPT_DYNAMIC Fri Dec 12 17:37:22 UTC 2025[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/5qakzg9g44siizj8li3r9blll9pw7bvp-nixos-system-docsserver-test/init regInfo=/nix/store/5l48w5m1s97d3i5gvqi6s37bd766i05w-closure-info/registration console=ttyS0,115200n8 console=tty0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000000] BIOS-provided physical RAM map:[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffdafff] usable[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000000] BIOS-e820: [mem 0x000000003ffdb000-0x000000003fffffff] reserved[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000000] NX (Execute Disable) protection: active[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000000] APIC: Static calls initialized[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000000] SMBIOS 2.8 present.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000000] DMI: Memory slots populated: 1/1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000000] Hypervisor detected: KVM[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000000] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000000] kvm-clock: using sched offset of 437862529 cycles[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000005] tsc: Detected 3399.998 MHz processor[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000708] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000891] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000899] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002684] found SMP MP-table at [mem 0x000f5470-0x000f547f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002695] Using GB pages for direct mapping[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002767] RAMDISK: [mem 0x3f3cd000-0x3ffcffff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002786] ACPI: Early table checksum verification disabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002789] ACPI: RSDP 0x00000000000F5290 000014 (v00 BOCHS )[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002792] ACPI: RSDT 0x000000003FFE23D9 000034 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002795] ACPI: FACP 0x000000003FFE228D 000074 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002799] ACPI: DSDT 0x000000003FFE0040 00224D (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002800] ACPI: FACS 0x000000003FFE0000 000040[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002802] ACPI: APIC 0x000000003FFE2301 000078 (v03 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002803] ACPI: HPET 0x000000003FFE2379 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002805] ACPI: WAET 0x000000003FFE23B1 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002806] ACPI: Reserving FACP table memory at [mem 0x3ffe228d-0x3ffe2300][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002807] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe228c][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002807] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002808] ACPI: Reserving APIC table memory at [mem 0x3ffe2301-0x3ffe2378][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002808] ACPI: Reserving HPET table memory at [mem 0x3ffe2379-0x3ffe23b0][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002809] ACPI: Reserving WAET table memory at [mem 0x3ffe23b1-0x3ffe23d8][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003560] No NUMA configuration found[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003561] Faking a node at [mem 0x0000000000000000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003564] NODE_DATA(0) allocated [mem 0x3ffd58c0-0x3ffdadff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003580] Zone ranges:[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003580]   DMA      [mem 0x0000000000001000-0x0000000000ffffff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003582]   DMA32    [mem 0x0000000001000000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003582]   Normal   empty[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003583]   Device   empty[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003584] Movable zone start for each node[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003584] Early memory node ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003584]   node   0: [mem 0x0000000000001000-0x000000000009efff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003585]   node   0: [mem 0x0000000000100000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003586] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003733] On node 0, zone DMA: 1 pages in unavailable ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003746] On node 0, zone DMA: 97 pages in unavailable ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.005512] On node 0, zone DMA32: 37 pages in unavailable ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007094] ACPI: PM-Timer IO Port: 0x608[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007119] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007202] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007204] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007205] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007206] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007207] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007208] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007210] ACPI: Using ACPI (MADT) for SMP configuration information[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007211] ACPI: HPET id: 0x8086a201 base: 0xfed00000[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007213] TSC deadline timer available[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007217] CPU topo: Max. logical packages:   1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007218] CPU topo: Max. logical dies:       1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007218] CPU topo: Max. dies per package:   1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007221] CPU topo: Max. threads per core:   1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007222] CPU topo: Num. cores per package:     1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007222] CPU topo: Num. threads per package:   1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007222] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007250] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007329] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Probing EDD (edd=off to disable)... oc[?7l[2J[0mk[    0.000000] Linux version 6.12.62 (nixbld@localhost) (gcc (GCC) 14.3.0, GNU ld (GNU Binutils) 2.44) #1-NixOS SMP PREEMPT_DYNAMIC Fri Dec 12 17:37:22 UTC 2025[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007331] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007332] [mem 0x40000000-0xfeffbfff] available for PCI devices[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007333] Booting paravirtualized kernel on KVM[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007335] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/001k9r4jinw78ih5x5hxggb82ynlbkqn-nixos-system-client-test/init regInfo=/nix/store/j7kn31fka8vp5bhm6sw8hv64iyzix7xw-closure-info/registration console=ttyS0,115200n8 console=tty0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.010913] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] BIOS-provided physical RAM map:[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.011227] percpu: Embedded 116 pages/cpu s294912 r65536 d114688 u2097152[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.011319] kvm-guest: PV spinlocks disabled, single CPU[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffdafff] usable[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] BIOS-e820: [mem 0x000000003ffdb000-0x000000003fffffff] reserved[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.011320] Kernel command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/5qakzg9g44siizj8li3r9blll9pw7bvp-nixos-system-docsserver-test/init regInfo=/nix/store/5l48w5m1s97d3i5gvqi6s37bd766i05w-closure-info/registration console=ttyS0,115200n8 console=tty0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.011397] Unknown kernel command line parameters "regInfo=/nix/store/5l48w5m1s97d3i5gvqi6s37bd766i05w-closure-info/registration", will be passed to user space.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] NX (Execute Disable) protection: active[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.011411] random: crng init done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] APIC: Static calls initialized[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.011470] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] SMBIOS 2.8 present.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.011502] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.011538] Fallback order for Node 0: 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.011540] Built 1 zonelists, mobility grouping on.  Total pages: 262009[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] DMI: Memory slots populated: 1/1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.011541] Policy zone: DMA32[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] Hypervisor detected: KVM[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.011771] mem auto-init: stack:all(zero), heap alloc:on, heap free:off[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.013902] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.014165] allocated 2097152 bytes of page_ext[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] kvm-clock: using sched offset of 438436129 cycles[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.014179] ftrace: allocating 46208 entries in 181 pages[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.022046] ftrace: allocated 181 pages with 5 groups[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000014] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.022679] Dynamic Preempt: voluntary[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000016] tsc: Detected 3399.998 MHz processor[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.022864] rcu: Preemptible hierarchical RCU implementation.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000711] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.022864] rcu: 	RCU event tracing is enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000897] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.022865] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.022866] 	Trampoline variant of Tasks RCU enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000905] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.022866] 	Rude variant of Tasks RCU enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002743] found SMP MP-table at [mem 0x000f5470-0x000f547f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.022866] 	Tracing variant of Tasks RCU enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002754] Using GB pages for direct mapping[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002870] RAMDISK: [mem 0x3f3cd000-0x3ffcffff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.022867] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002893] ACPI: Early table checksum verification disabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.022868] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002895] ACPI: RSDP 0x00000000000F5290 000014 (v00 BOCHS )[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.022874] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002898] ACPI: RSDT 0x000000003FFE23D9 000034 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.022876] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002902] ACPI: FACP 0x000000003FFE228D 000074 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002905] ACPI: DSDT 0x000000003FFE0040 00224D (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.022876] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002907] ACPI: FACS 0x000000003FFE0000 000040[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.026372] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002909] ACPI: APIC 0x000000003FFE2301 000078 (v03 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.026621] rcu: srcu_init: Setting srcu_struct sizes based on contention.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002910] ACPI: HPET 0x000000003FFE2379 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.026696] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.040699] Console: colour VGA+ 80x25[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002912] ACPI: WAET 0x000000003FFE23B1 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.040703] printk: legacy console [tty0] enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.144775] printk: legacy console [ttyS0] enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002913] ACPI: Reserving FACP table memory at [mem 0x3ffe228d-0x3ffe2300][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.393998] ACPI: Core revision 20240827[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002914] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe228c][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002914] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.396195] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002915] ACPI: Reserving APIC table memory at [mem 0x3ffe2301-0x3ffe2378][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.400401] APIC: Switch to symmetric I/O mode setup[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002915] ACPI: Reserving HPET table memory at [mem 0x3ffe2379-0x3ffe23b0][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.403606] x2apic enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002916] ACPI: Reserving WAET table memory at [mem 0x3ffe23b1-0x3ffe23d8][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003676] No NUMA configuration found[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003677] Faking a node at [mem 0x0000000000000000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.405906] APIC: Switched APIC routing to: physical x2apic[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003680] NODE_DATA(0) allocated [mem 0x3ffd58c0-0x3ffdadff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003695] Zone ranges:[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003695]   DMA      [mem 0x0000000000001000-0x0000000000ffffff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003696]   DMA32    [mem 0x0000000001000000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.412796] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003697]   Normal   empty[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003698]   Device   empty[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003698] Movable zone start for each node[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003699] Early memory node ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.415721] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x31024cfe468, max_idle_ns: 440795307017 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003699]   node   0: [mem 0x0000000000001000-0x000000000009efff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003700]   node   0: [mem 0x0000000000100000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.420592] Calibrating delay loop (skipped) preset value.. 6799.99 BogoMIPS (lpj=3399998)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003701] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003839] On node 0, zone DMA: 1 pages in unavailable ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.423153] x86/cpu: User Mode Instruction Prevention (UMIP) activated[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003851] On node 0, zone DMA: 97 pages in unavailable ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.425956] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.005417] On node 0, zone DMA32: 37 pages in unavailable ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.006978] ACPI: PM-Timer IO Port: 0x608[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.427591] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007030] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007119] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.429601] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007121] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.431592] Spectre V2 : Mitigation: Retpolines[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007122] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007123] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.432590] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007124] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007124] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.434591] Spectre V2 : Enabling Restricted Speculation for firmware calls[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007127] ACPI: Using ACPI (MADT) for SMP configuration information[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007127] ACPI: HPET id: 0x8086a201 base: 0xfed00000[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007130] TSC deadline timer available[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.436593] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007134] CPU topo: Max. logical packages:   1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007134] CPU topo: Max. logical dies:       1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007135] CPU topo: Max. dies per package:   1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007138] CPU topo: Max. threads per core:   1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.438593] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007138] CPU topo: Num. cores per package:     1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.440591] active return thunk: srso_alias_return_thunk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007139] CPU topo: Num. threads per package:   1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007139] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.441591] Speculative Return Stack Overflow: Mitigation: Safe RET[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007169] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007224] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.443591] Transient Scheduler Attacks: Forcing mitigation on in a VM[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007225] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.445591] Transient Scheduler Attacks: Vulnerable: Clear CPU buffers attempted, no microcode[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007227] [mem 0x40000000-0xfeffbfff] available for PCI devices[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007228] Booting paravirtualized kernel on KVM[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.447676] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007230] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.010764] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.010992] percpu: Embedded 116 pages/cpu s294912 r65536 d114688 u2097152[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011083] kvm-guest: PV spinlocks disabled, single CPU[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.449592] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.451592] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011084] Kernel command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/001k9r4jinw78ih5x5hxggb82ynlbkqn-nixos-system-client-test/init regInfo=/nix/store/j7kn31fka8vp5bhm6sw8hv64iyzix7xw-closure-info/registration console=ttyS0,115200n8 console=tty0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.453591] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.455592] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011161] Unknown kernel command line parameters "regInfo=/nix/store/j7kn31fka8vp5bhm6sw8hv64iyzix7xw-closure-info/registration", will be passed to user space.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011175] random: crng init done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.457591] x86/fpu: xstate_offset[9]:  832, xstate_sizes[9]:    8[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011227] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011256] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.459591] x86/fpu: Enabled xstate features 0x207, context size is 840 bytes, using 'compacted' format.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011292] Fallback order for Node 0: 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011294] Built 1 zonelists, mobility grouping on.  Total pages: 262009[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011295] Policy zone: DMA32[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011475] mem auto-init: stack:all(zero), heap alloc:on, heap free:off[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.013624] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.013850] allocated 2097152 bytes of page_ext[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.013864] ftrace: allocating 46208 entries in 181 pages[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.021606] ftrace: allocated 181 pages with 5 groups[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.022214] Dynamic Preempt: voluntary[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.022381] rcu: Preemptible hierarchical RCU implementation.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.022382] rcu: 	RCU event tracing is enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.022382] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.022383] 	Trampoline variant of Tasks RCU enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.022384] 	Rude variant of Tasks RCU enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.022384] 	Tracing variant of Tasks RCU enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.022385] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.488079] Freeing SMP alternatives memory: 40K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.022385] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.489593] pid_max: default: 32768 minimum: 301[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.022393] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.491654] LSM: initializing lsm=capability,landlock,yama,bpf[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.493682] landlock: Up and running.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.022394] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.494591] Yama: becoming mindful.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.022395] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.496808] LSM support for eBPF active[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.025971] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.026265] rcu: srcu_init: Setting srcu_struct sizes based on contention.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.497628] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.026339] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.039604] Console: colour VGA+ 80x25[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.499593] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.039607] printk: legacy console [tty0] enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.146942] printk: legacy console [ttyS0] enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.502840] smpboot: CPU0: AMD Ryzen 9 5950X 16-Core Processor (family: 0x19, model: 0x21, stepping: 0x0)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.406963] ACPI: Core revision 20240827[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.504871] Performance Events: Fam17h+ core perfctr, AMD PMU driver.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.409315] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.506606] ... version:                0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.507597] ... bit width:              48[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.413750] APIC: Switch to symmetric I/O mode setup[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.508598] ... generic registers:      6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.417263] x2apic enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.509598] ... value mask:             0000ffffffffffff[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.419700] APIC: Switched APIC routing to: physical x2apic[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.510597] ... max period:             00007fffffffffff[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.511598] ... fixed-purpose events:   0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.512598] ... event mask:             000000000000003f[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.426474] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.513708] signal: max sigframe size: 3376[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.514641] rcu: Hierarchical SRCU implementation.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.429431] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x31024cfe468, max_idle_ns: 440795307017 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.515598] rcu: 	Max phase no-delay instances is 400.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.434551] Calibrating delay loop (skipped) preset value.. 6799.99 BogoMIPS (lpj=3399998)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.520651] smp: Bringing up secondary CPUs ...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.438178] x86/cpu: User Mode Instruction Prevention (UMIP) activated[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.521612] smp: Brought up 1 node, 1 CPU[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.440578] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.522602] smpboot: Total of 1 processors activated (6799.99 BogoMIPS)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.441549] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.443560] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.524934] Memory: 962124K/1048036K available (16384K kernel code, 2645K rwdata, 12580K rodata, 3408K init, 3360K bss, 78836K reserved, 0K cma-reserved)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.445551] Spectre V2 : Mitigation: Retpolines[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.526772] devtmpfs: initialized[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.447550] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.527708] x86/mm: Memory block size: 128MB[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.449550] Spectre V2 : Enabling Restricted Speculation for firmware calls[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.530277] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.451552] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.531601] futex hash table entries: 256 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.533655] pinctrl core: initialized pinctrl subsystem[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.453550] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.455550] active return thunk: srso_alias_return_thunk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.534976] PM: RTC time: 21:20:10, date: 2026-08-06[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.457550] Speculative Return Stack Overflow: Mitigation: Safe RET[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.537548] NET: Registered PF_NETLINK/PF_ROUTE protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.459550] Transient Scheduler Attacks: Forcing mitigation on in a VM[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.538755] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.461549] Transient Scheduler Attacks: Vulnerable: Clear CPU buffers attempted, no microcode[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.540606] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.463641] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.542605] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.544611] audit: initializing netlink subsys (disabled)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.545648] audit: type=2000 audit(1786051210.449:1): state=initialized audit_enabled=0 res=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.547745] thermal_sys: Registered thermal governor 'bang_bang'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.466548] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.547748] thermal_sys: Registered thermal governor 'step_wise'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.468550] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.549601] thermal_sys: Registered thermal governor 'user_space'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.550612] cpuidle: using governor menu[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.470550] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.472550] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.554705] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.474549] x86/fpu: xstate_offset[9]:  832, xstate_sizes[9]:    8[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.556912] PCI: Using configuration type 1 for base access[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.558598] PCI: Using configuration type 1 for extended access[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.476549] x86/fpu: Enabled xstate features 0x207, context size is 840 bytes, using 'compacted' format.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.559826] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.504111] Freeing SMP alternatives memory: 40K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.582811] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.505550] pid_max: default: 32768 minimum: 301[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.584599] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.507596] LSM: initializing lsm=capability,landlock,yama,bpf[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.510553] landlock: Up and running.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.585601] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.511549] Yama: becoming mindful.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.586600] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.513764] LSM support for eBPF active[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.515575] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.592709] ACPI: Added _OSI(Module Device)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.517551] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.593600] ACPI: Added _OSI(Processor Device)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.594601] ACPI: Added _OSI(Processor Aggregator Device)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.519967] smpboot: CPU0: AMD Ryzen 9 5950X 16-Core Processor (family: 0x19, model: 0x21, stepping: 0x0)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.521869] Performance Events: Fam17h+ core perfctr, AMD PMU driver.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.597381] ACPI: 1 ACPI AML tables successfully acquired and loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.523564] ... version:                0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.524556] ... bit width:              48[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.599855] ACPI: Interpreter enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.525556] ... generic registers:      6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.600639] ACPI: PM: (supports S0 S3 S4 S5)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.526556] ... value mask:             0000ffffffffffff[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.601598] ACPI: Using IOAPIC for interrupt routing[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.527556] ... max period:             00007fffffffffff[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.528556] ... fixed-purpose events:   0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.602610] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.529556] ... event mask:             000000000000003f[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.604597] PCI: Using E820 reservations for host bridge windows[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.530639] signal: max sigframe size: 3376[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.605757] ACPI: Enabled 2 GPEs in block 00 to 0F[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.531590] rcu: Hierarchical SRCU implementation.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.532556] rcu: 	Max phase no-delay instances is 400.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.610529] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.536748] smp: Bringing up secondary CPUs ...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.611601] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.537567] smp: Brought up 1 node, 1 CPU[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.613984] acpiphp: Slot [3] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.538558] smpboot: Total of 1 processors activated (6799.99 BogoMIPS)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.615637] acpiphp: Slot [4] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.616637] acpiphp: Slot [5] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.617634] acpiphp: Slot [6] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.618636] acpiphp: Slot [7] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.539896] Memory: 962116K/1048036K available (16384K kernel code, 2645K rwdata, 12580K rodata, 3408K init, 3360K bss, 78836K reserved, 0K cma-reserved)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.541674] devtmpfs: initialized[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.619634] acpiphp: Slot [8] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.620653] acpiphp: Slot [9] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.542667] x86/mm: Memory block size: 128MB[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.621637] acpiphp: Slot [10] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.622635] acpiphp: Slot [11] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.544027] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.623647] acpiphp: Slot [12] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.624637] acpiphp: Slot [13] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.545562] futex hash table entries: 256 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.625633] acpiphp: Slot [14] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.546617] pinctrl core: initialized pinctrl subsystem[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.626635] acpiphp: Slot [15] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.547915] PM: RTC time: 21:20:10, date: 2026-08-06[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.627637] acpiphp: Slot [16] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.628636] acpiphp: Slot [17] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.550129] NET: Registered PF_NETLINK/PF_ROUTE protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.629640] acpiphp: Slot [18] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.630635] acpiphp: Slot [19] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.551707] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.631636] acpiphp: Slot [20] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.553568] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.632634] acpiphp: Slot [21] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.633635] acpiphp: Slot [22] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.555567] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.634635] acpiphp: Slot [23] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.635642] acpiphp: Slot [24] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.557566] audit: initializing netlink subsys (disabled)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.636637] acpiphp: Slot [25] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.558604] audit: type=2000 audit(1786051210.603:1): state=initialized audit_enabled=0 res=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.637635] acpiphp: Slot [26] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.638639] acpiphp: Slot [27] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.560689] thermal_sys: Registered thermal governor 'bang_bang'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.639641] acpiphp: Slot [28] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.560691] thermal_sys: Registered thermal governor 'step_wise'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.640638] acpiphp: Slot [29] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.641648] acpiphp: Slot [30] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.562562] thermal_sys: Registered thermal governor 'user_space'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.642640] acpiphp: Slot [31] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.563569] cpuidle: using governor menu[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.643628] PCI host bridge to bus 0000:00[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.644602] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.567409] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.568850] PCI: Using configuration type 1 for base access[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.645598] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.570557] PCI: Using configuration type 1 for extended access[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.646598] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.571711] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.648597] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.650598] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.652597] pci_bus 0000:00: root bus resource [bus 00-ff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.654039] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.656733] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.660128] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.593728] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.664999] pci 0000:00:01.1: BAR 4 [io  0xc1e0-0xc1ef][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.595555] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.597558] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.668052] pci 0000:00:01.1: BAR 0 [io  0x01f0-0x01f7]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.598555] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.669597] pci 0000:00:01.1: BAR 1 [io  0x03f6]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.670597] pci 0000:00:01.1: BAR 2 [io  0x0170-0x0177]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.671597] pci 0000:00:01.1: BAR 3 [io  0x0376]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.605157] ACPI: Added _OSI(Module Device)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.606556] ACPI: Added _OSI(Processor Device)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.672970] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.607556] ACPI: Added _OSI(Processor Aggregator Device)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.677157] pci 0000:00:01.2: BAR 4 [io  0xc100-0xc11f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.610327] ACPI: 1 ACPI AML tables successfully acquired and loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.612773] ACPI: Interpreter enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.613571] ACPI: PM: (supports S0 S3 S4 S5)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.681768] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.614556] ACPI: Using IOAPIC for interrupt routing[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.684852] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.615580] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.686621] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.617556] PCI: Using E820 reservations for host bridge windows[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.618753] ACPI: Enabled 2 GPEs in block 00 to 0F[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.688128] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.623686] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.691242] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.624560] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.626938] acpiphp: Slot [3] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.695220] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.628631] acpiphp: Slot [4] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.629618] acpiphp: Slot [5] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.630595] acpiphp: Slot [6] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.631595] acpiphp: Slot [7] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.632596] acpiphp: Slot [8] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.702283] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.633597] acpiphp: Slot [9] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.634606] acpiphp: Slot [10] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.703762] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.635603] acpiphp: Slot [11] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.636594] acpiphp: Slot [12] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.706452] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.637597] acpiphp: Slot [13] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.638592] acpiphp: Slot [14] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.639595] acpiphp: Slot [15] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.708598] pci 0000:00:03.0: BAR 0 [io  0xc120-0xc13f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.640597] acpiphp: Slot [16] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.710597] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.641594] acpiphp: Slot [17] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.642593] acpiphp: Slot [18] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.643596] acpiphp: Slot [19] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.644605] acpiphp: Slot [20] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.645595] acpiphp: Slot [21] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.715597] pci 0000:00:03.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.646596] acpiphp: Slot [22] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.647597] acpiphp: Slot [23] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.648597] acpiphp: Slot [24] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.718597] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.649596] acpiphp: Slot [25] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.650597] acpiphp: Slot [26] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.651595] acpiphp: Slot [27] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.722741] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.652597] acpiphp: Slot [28] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.653596] acpiphp: Slot [29] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.725596] pci 0000:00:04.0: BAR 0 [io  0xc140-0xc15f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.654615] acpiphp: Slot [30] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.655614] acpiphp: Slot [31] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.727602] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.656592] PCI host bridge to bus 0000:00[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.657561] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.658557] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.732598] pci 0000:00:04.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.659556] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.661556] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.737618] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.663558] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.740597] pci 0000:00:05.0: BAR 0 [io  0xc080-0xc0bf][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.665558] pci_bus 0000:00: root bus resource [bus 00-ff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.742596] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.667044] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.669725] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.746597] pci 0000:00:05.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.673247] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.751254] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.678556] pci 0000:00:01.1: BAR 4 [io  0xc1e0-0xc1ef][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.753602] pci 0000:00:06.0: BAR 0 [io  0xc160-0xc17f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.756597] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.681107] pci 0000:00:01.1: BAR 0 [io  0x01f0-0x01f7]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.682557] pci 0000:00:01.1: BAR 1 [io  0x03f6]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.683557] pci 0000:00:01.1: BAR 2 [io  0x0170-0x0177]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.760597] pci 0000:00:06.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.684556] pci 0000:00:01.1: BAR 3 [io  0x0376]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.686015] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.766280] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.768596] pci 0000:00:07.0: BAR 0 [io  0xc180-0xc19f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.690910] pci 0000:00:01.2: BAR 4 [io  0xc100-0xc11f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.770597] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.694836] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.775302] pci 0000:00:07.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.698118] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.699580] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.701128] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.780232] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.782597] pci 0000:00:08.0: BAR 0 [io  0xc000-0xc07f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.704111] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.784597] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.708160] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.714557] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.789322] pci 0000:00:08.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.715709] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.794255] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.718361] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.796597] pci 0000:00:09.0: BAR 0 [io  0xc1a0-0xc1bf][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.720557] pci 0000:00:03.0: BAR 0 [io  0xc120-0xc13f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.799600] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.722558] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.803597] pci 0000:00:09.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.727558] pci 0000:00:03.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.730462] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.734305] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.806600] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.736555] pci 0000:00:04.0: BAR 0 [io  0xc140-0xc15f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.738534] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.811285] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.814008] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.742233] pci 0000:00:04.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.817912] pci 0000:00:0a.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.747883] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.750555] pci 0000:00:05.0: BAR 0 [io  0xc080-0xc0bf][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.823091] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.826097] pci 0000:00:0b.0: BAR 0 [io  0xc0c0-0xc0ff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.752558] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.828519] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.757555] pci 0000:00:05.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.833226] pci 0000:00:0b.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.763539] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.838472] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.765557] pci 0000:00:06.0: BAR 0 [io  0xc160-0xc17f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.840597] pci 0000:00:0c.0: BAR 0 [io  0xc1c0-0xc1df][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.767527] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.842608] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.771245] pci 0000:00:06.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.846597] pci 0000:00:0c.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.776393] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.851629] ACPI: PCI: Interrupt link LNKA configured for IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.778555] pci 0000:00:07.0: BAR 0 [io  0xc180-0xc19f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.853857] ACPI: PCI: Interrupt link LNKB configured for IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.781438] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.855850] ACPI: PCI: Interrupt link LNKC configured for IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.857820] ACPI: PCI: Interrupt link LNKD configured for IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.785201] pci 0000:00:07.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.859705] ACPI: PCI: Interrupt link LNKS configured for IRQ 9[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.862172] iommu: Default domain type: Translated[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.863597] iommu: DMA domain TLB invalidation policy: lazy mode[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.790269] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.864637] ACPI: bus type USB registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.865631] usbcore: registered new interface driver usbfs[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.793012] pci 0000:00:08.0: BAR 0 [io  0xc000-0xc07f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.866606] usbcore: registered new interface driver hub[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.867607] usbcore: registered new device driver usb[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.795556] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.868969] NetLabel: Initializing[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.869597] NetLabel:  domain hash size = 128[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.870597] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.801103] pci 0000:00:08.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.871618] NetLabel:  unlabeled traffic allowed by default[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.872599] PCI: Using ACPI for IRQ routing[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.874489] pci 0000:00:02.0: vgaarb: setting as boot VGA device[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.806319] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.874588] pci 0000:00:02.0: vgaarb: bridge control possible[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.808555] pci 0000:00:09.0: BAR 0 [io  0xc1a0-0xc1bf][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.874588] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.874600] vgaarb: loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.810556] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.875688] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.876597] hpet0: 3 comparators, 64-bit 100.000000 MHz counter[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.814555] pci 0000:00:09.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.879718] clocksource: Switched to clocksource kvm-clock[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.883555] VFS: Disk quotas dquot_6.6.0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.885566] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.888904] pnp: PnP ACPI init[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.891194] pnp: PnP ACPI: found 6 devices[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.816557] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.821233] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.899203] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.823932] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.903209] clocksource: Switched to clocksource acpi_pm[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.905868] NET: Registered PF_INET protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.827555] pci 0000:00:0a.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.908349] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.831787] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.834561] pci 0000:00:0b.0: BAR 0 [io  0xc0c0-0xc0ff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.836498] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.922357] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.926298] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.840995] pci 0000:00:0b.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.930061] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.933806] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.846695] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.937593] TCP: Hash tables configured (established 8192 bind 8192)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.849555] pci 0000:00:0c.0: BAR 0 [io  0xc1c0-0xc1df][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.940622] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.944225] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.851442] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.947375] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.950782] NET: Registered PF_UNIX/PF_LOCAL protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.855168] pci 0000:00:0c.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.953599] NET: Registered PF_XDP protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.956124] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.861073] ACPI: PCI: Interrupt link LNKA configured for IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.959173] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.862787] ACPI: PCI: Interrupt link LNKB configured for IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.962315] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.864765] ACPI: PCI: Interrupt link LNKC configured for IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.965625] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.866843] ACPI: PCI: Interrupt link LNKD configured for IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.969547] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.868657] ACPI: PCI: Interrupt link LNKS configured for IRQ 9[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.972944] pci 0000:00:01.0: PIIX3: Enabling Passive Release[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.869980] iommu: Default domain type: Translated[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.975794] pci 0000:00:00.0: Limiting direct PCI/PCI transfers[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.871556] iommu: DMA domain TLB invalidation policy: lazy mode[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.872605] ACPI: bus type USB registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.980231] ACPI: \_SB_.LNKD: Enabled at IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.873578] usbcore: registered new interface driver usbfs[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.984625] PCI: CLS 0 bytes, default 64[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.874565] usbcore: registered new interface driver hub[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.875588] usbcore: registered new device driver usb[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.987047] Trying to unpack rootfs image as initramfs...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.877060] NetLabel: Initializing[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.878556] NetLabel:  domain hash size = 128[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.879556] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.992127] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x31024cfe468, max_idle_ns: 440795307017 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.880576] NetLabel:  unlabeled traffic allowed by default[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.881559] PCI: Using ACPI for IRQ routing[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.883320] pci 0000:00:02.0: vgaarb: setting as boot VGA device[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.883547] pci 0000:00:02.0: vgaarb: bridge control possible[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.883547] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.883559] vgaarb: loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.884632] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.885556] hpet0: 3 comparators, 64-bit 100.000000 MHz counter[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.888628] clocksource: Switched to clocksource kvm-clock[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.023302] Initialise system trusted keyrings[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.892897] VFS: Disk quotas dquot_6.6.0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.895004] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.898415] pnp: PnP ACPI init[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.028211] workingset: timestamp_bits=40 max_order=18 bucket_order=0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.900700] pnp: PnP ACPI: found 6 devices[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.908992] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.913071] clocksource: Switched to clocksource acpi_pm[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.915760] NET: Registered PF_INET protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.918267] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.054046] Key type asymmetric registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.059175] Asymmetric key parser 'x509' registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.931381] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.066075] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.935189] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.938779] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.074245] io scheduler mq-deadline registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.942455] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.076602] io scheduler kyber registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.945907] TCP: Hash tables configured (established 8192 bind 8192)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.948962] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.082568] Freeing initrd memory: 12300K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.952475] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.085142] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.955592] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.088528] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.958944] NET: Registered PF_UNIX/PF_LOCAL protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.961711] NET: Registered PF_XDP protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.093297] Linux agpgart interface v0.103[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.095312] ACPI: bus type drm_connector registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.964024] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.966884] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.098106] usbcore: registered new interface driver usbserial_generic[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.969796] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.101137] usbserial: USB Serial support registered for generic[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.972961] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.104059] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.976187] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.107795] drop_monitor: Initializing network drop monitor service[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.979474] pci 0000:00:01.0: PIIX3: Enabling Passive Release[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.111119] NET: Registered PF_INET6 protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.982372] pci 0000:00:00.0: Limiting direct PCI/PCI transfers[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.114302] Segment Routing with IPv6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.116479] In-situ OAM (IOAM) with IPv6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.986626] ACPI: \_SB_.LNKD: Enabled at IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.118857] IPI shorthand broadcast: enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.990989] PCI: CLS 0 bytes, default 64[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.123305] registered taskstats version 1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.993219] Trying to unpack rootfs image as initramfs...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.125640] Loading compiled-in X.509 certificates[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.997423] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x31024cfe468, max_idle_ns: 440795307017 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.132848] Demotion targets for Node 0: null[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.135358] Key type .fscrypt registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.137488] Key type fscrypt-provisioning registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.140191] PM:   Magic number: 6:961:347[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.142250]  pci0000:00: hash matches[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.144792] RAS: Correctable Errors collector initialized.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.150607] Unstable clock detected, switching default tracing clock to "global"[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.150607] If you want to keep using the local clock, then add:[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.150607]   "trace_clock=local"[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.150607] on the kernel command line[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.159606] clk: Disabling unused clocks[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.029592] Initialise system trusted keyrings[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.161655] PM: genpd: Disabling unused power domains[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.165271] Freeing unused decrypted memory: 2028K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.034521] workingset: timestamp_bits=40 max_order=18 bucket_order=0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.168150] Freeing unused kernel image (initmem) memory: 3408K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.171023] Write protecting the kernel read-only data: 30720k[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.174201] Freeing unused kernel image (rodata/data gap) memory: 1756K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.060477] Key type asymmetric registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.065537] Asymmetric key parser 'x509' registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.072429] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.080438] io scheduler mq-deadline registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.082620] io scheduler kyber registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.089375] Freeing initrd memory: 12300K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.219932] x86/mm: Checked W+X mappings: passed, no W+X pages found.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.223082] Run /init as init process[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.091808] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.095199] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.100077] Linux agpgart interface v0.103[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.102165] ACPI: bus type drm_connector registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.104930] usbcore: registered new interface driver usbserial_generic[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.107986] usbserial: USB Serial support registered for generic[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.110919] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.114417] drop_monitor: Initializing network drop monitor service[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.117378] NET: Registered PF_INET6 protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.120358] Segment Routing with IPv6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.122243] In-situ OAM (IOAM) with IPv6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.125180] IPI shorthand broadcast: enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.130189] registered taskstats version 1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.132615] Loading compiled-in X.509 certificates[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.140639] Demotion targets for Node 0: null[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.271282] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.143116] Key type .fscrypt registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.145333] Key type fscrypt-provisioning registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.148167] PM:   Magic number: 6:961:347[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.150357]  pci0000:00: hash matches[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.153032] RAS: Correctable Errors collector initialized.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.159425] Unstable clock detected, switching default tracing clock to "global"[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.159425] If you want to keep using the local clock, then add:[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.159425]   "trace_clock=local"[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.159425] on the kernel command line[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.168366] clk: Disabling unused clocks[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.170387] PM: genpd: Disabling unused power domains[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.173953] Freeing unused decrypted memory: 2028K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.176727] Freeing unused kernel image (initmem) memory: 3408K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.179731] Write protecting the kernel read-only data: 30720k[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.183078] Freeing unused kernel image (rodata/data gap) memory: 1756K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.228791] x86/mm: Checked W+X mappings: passed, no W+X pages found.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.231922] Run /init as init process[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.280101] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.451219] ACPI: \_SB_.LNKC: Enabled at IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.459844] uhci_hcd 0000:00:01.2: UHCI Host Controller[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.465540] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.479382] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.482899] SCSI subsystem initialized[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.493577] serio: i8042 KBD port at 0x60,0x64 irq 1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.498641] uhci_hcd 0000:00:01.2: detected 2 ports[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.507064] serio: i8042 AUX port at 0x60,0x64 irq 12[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.514274] ACPI: \_SB_.LNKA: Enabled at IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.517098] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.529050] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.12[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.533663] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.538245] usb usb1: Product: UHCI Host Controller[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.540668] usb usb1: Manufacturer: Linux 6.12.62 uhci_hcd[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.543460] usb usb1: SerialNumber: 0000:00:01.2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.556935] ACPI: \_SB_.LNKB: Enabled at IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.567157] scsi host0: ata_piix[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.574260] scsi host1: ata_piix[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.578482] hub 1-0:1.0: USB hub found[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.582571] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 lpm-pol 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.586158] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.456530] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.593211] hub 1-0:1.0: 2 ports detected[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.480129] uhci_hcd 0000:00:01.2: UHCI Host Controller[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.491087] ACPI: \_SB_.LNKC: Enabled at IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.493948] serio: i8042 KBD port at 0x60,0x64 irq 1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.496731] SCSI subsystem initialized[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.498640] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.504363] serio: i8042 AUX port at 0x60,0x64 irq 12[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.511409] uhci_hcd 0000:00:01.2: detected 2 ports[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.524585] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.531438] ACPI: \_SB_.LNKA: Enabled at IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.536520] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.12[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.540134] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.543461] usb usb1: Product: UHCI Host Controller[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.545968] usb usb1: Manufacturer: Linux 6.12.62 uhci_hcd[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.548713] usb usb1: SerialNumber: 0000:00:01.2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.559490] scsi host0: ata_piix[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.562730] ACPI: \_SB_.LNKB: Enabled at IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.565924] scsi host1: ata_piix[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.570065] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 lpm-pol 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.573830] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.579718] hub 1-0:1.0: USB hub found[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.585718] hub 1-0:1.0: 2 ports detected[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.741609] ata2: found unknown device (class 0)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.745609] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.750370] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.813204] usb 1-1: new full-speed USB device number 2 using uhci_hcd[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.841762] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.862485] virtio_blk virtio5: 1/0/0 default/read/poll queues[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.732405] ata2: found unknown device (class 0)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.736007] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.740882] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.894394] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.905204] netfs: FS-Cache loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.910467] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.913801] cdrom: Uniform CD-ROM driver Revision: 3.20[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.930767] 9pnet: Installing 9P2000 support[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.811388] usb 1-1: new full-speed USB device number 2 using uhci_hcd[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.830551] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.856026] virtio_blk virtio5: 1/0/0 default/read/poll queues[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.988177] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.992370] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.995709] usb 1-1: Product: QEMU USB Tablet[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.997932] usb 1-1: Manufacturer: QEMU[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.999885] usb 1-1: SerialNumber: 28754-0000:00:01.2-1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.884830] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.891136] netfs: FS-Cache loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.901811] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.904952] cdrom: Uniform CD-ROM driver Revision: 3.20[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.036153] hid: raw HID events driver (C) Jiri Kosina[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.916633] 9pnet: Installing 9P2000 support[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.048345] usbcore: registered new interface driver usbhid[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.051261] usbhid: USB HID core driver[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.056111] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.062488] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.984001] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.988062] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.992379] usb 1-1: Product: QEMU USB Tablet[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.994908] usb 1-1: Manufacturer: QEMU[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.997097] usb 1-1: SerialNumber: 28754-0000:00:01.2-1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.024076] hid: raw HID events driver (C) Jiri Kosina[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.035266] usbcore: registered new interface driver usbhid[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.037916] usbhid: USB HID core driver[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.043919] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.049784] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.229133] EXT4-fs (vda): mounted filesystem 8bafdc3e-a9c6-4182-85ff-ada38abb7a0a r/w with ordered data mode. Quota mode: none.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.254904] 9p: Installing v9fs 9p2000 file system support[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.201066] EXT4-fs (vda): mounted filesystem 13ea7290-ac56-4d64-9292-a47a8c0330df r/w with ordered data mode. Quota mode: none.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.222228] 9p: Installing v9fs 9p2000 file system support[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.510148] EXT4-fs (vda): re-mounted 8bafdc3e-a9c6-4182-85ff-ada38abb7a0a.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.515263] booting system configuration /nix/store/5qakzg9g44siizj8li3r9blll9pw7bvp-nixos-system-docsserver-test[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.464401] EXT4-fs (vda): re-mounted 13ea7290-ac56-4d64-9292-a47a8c0330df.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.469227] booting system configuration /nix/store/001k9r4jinw78ih5x5hxggb82ynlbkqn-nixos-system-client-test[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    4.428360] systemd[1]: Inserted module 'autofs4'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    4.297775] systemd[1]: Inserted module 'autofs4'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    4.482839] systemd[1]: systemd 258.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN -IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    4.351726] systemd[1]: systemd 258.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN -IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    4.365871] systemd[1]: Detected virtualization kvm.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    4.496832] systemd[1]: Detected virtualization kvm.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    4.368378] systemd[1]: Detected architecture x86-64.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    4.500206] systemd[1]: Detected architecture x86-64.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    4.370823] systemd[1]: Detected first boot.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    4.502822] systemd[1]: Detected first boot.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    4.379122] systemd[1]: Initializing machine ID from random generator.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    4.510876] systemd[1]: Initializing machine ID from random generator.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    4.431019] systemd[1]: Hostname set to <client>.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    4.562116] systemd[1]: Hostname set to <docsserver>.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    4.679311] systemd[1]: bpf-restrict-fs: LSM BPF program attached[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    4.552256] systemd[1]: bpf-restrict-fs: LSM BPF program attached[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    4.842311] systemd[1]: Applying preset policy.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    4.714132] systemd[1]: Applying preset policy.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.393534] systemd[1]: Populated /etc with preset unit settings.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.275475] systemd[1]: Populated /etc with preset unit settings.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.801318] systemd[1]: /etc/systemd/system/centjes-docs-site-production.service:3: Invalid URL, ignoring: /nix/store/d5g8rpx497v2ywm07q1pfjg7pdgi1761-settings-check[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.912129] systemd[1]: Queued start job for default target Multi-User System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.781069] systemd[1]: Queued start job for default target Multi-User System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.945213] systemd[1]: Created slice Slice /system/getty.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.816026] systemd[1]: Created slice Slice /system/getty.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.947948] systemd[1]: Created slice Slice /system/modprobe.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.818963] systemd[1]: Created slice Slice /system/modprobe.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.951445] systemd[1]: Created slice User and Session Slice.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.821827] systemd[1]: Created slice User and Session Slice.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.954776] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.957831] systemd[1]: Started Forward Password Requests to Wall Directory Watch.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.960839] systemd[1]: Expecting device /dev/hvc0...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.962811] systemd[1]: Expecting device /dev/ttyS0...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.964872] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.967600] systemd[1]: Reached target Local Encrypted Volumes.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.970114] systemd[1]: Reached target Virtual Machines and Containers.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.972530] systemd[1]: Reached target Path Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.974438] systemd[1]: Reached target Remote File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.976527] systemd[1]: Reached target Slice Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.978462] systemd[1]: Reached target Swaps.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.984803] systemd[1]: Listening on Process Core Dump Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.989654] systemd[1]: Listening on Credential Encryption/Decryption.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.992611] systemd[1]: Listening on Journal Audit Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.863064] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.995137] systemd[1]: Listening on Journal Socket (/dev/log).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.997747] systemd[1]: Listening on Journal Sockets.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.866562] systemd[1]: Started Forward Password Requests to Wall Directory Watch.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.000715] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.003562] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.007651] systemd[1]: Listening on udev Control Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.009863] systemd[1]: Listening on udev Kernel Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.016397] systemd[1]: Mounting Huge Pages File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.021900] systemd[1]: Mounting POSIX Message Queue File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.891092] systemd[1]: Expecting device /dev/hvc0...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.893723] systemd[1]: Expecting device /dev/ttyS0...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.896055] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.030113] systemd[1]: Mounting Kernel Debug File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.899106] systemd[1]: Reached target Local Encrypted Volumes.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.901818] systemd[1]: Reached target Virtual Machines and Containers.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.904657] systemd[1]: Reached target Path Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.906767] systemd[1]: Reached target Remote File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.909122] systemd[1]: Reached target Slice Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.911180] systemd[1]: Reached target Swaps.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.043298] systemd[1]: Mounting Kernel Trace File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.923621] systemd[1]: Listening on Process Core Dump Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.928636] systemd[1]: Listening on Credential Encryption/Decryption.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.060716] systemd[1]: Starting Create List of Static Device Nodes...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.931842] systemd[1]: Listening on Journal Audit Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.934382] systemd[1]: Listening on Journal Socket (/dev/log).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.936919] systemd[1]: Listening on Journal Sockets.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.939620] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.070874] systemd[1]: Load Kernel Module 9pnet_virtio was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!9pnet_virtio).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.942858] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.947516] systemd[1]: Listening on udev Control Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.949989] systemd[1]: Listening on udev Kernel Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.956478] systemd[1]: Mounting Huge Pages File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.092302] systemd[1]: Starting Load Kernel Module configfs...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.962071] systemd[1]: Mounting POSIX Message Queue File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.096246] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.970425] systemd[1]: Mounting Kernel Debug File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.106256] systemd[1]: Starting Load Kernel Module efi_pstore...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.115504] systemd[1]: Starting Load Kernel Module fuse...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.985481] systemd[1]: Mounting Kernel Trace File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.120161] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.126378] systemd[1]: Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.999470] systemd[1]: Starting Create List of Static Device Nodes...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.008240] systemd[1]: Load Kernel Module 9pnet_virtio was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!9pnet_virtio).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.146927] systemd[1]: Starting Journal Service...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.159937] systemd[1]: Starting Load Kernel Modules...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.034021] systemd[1]: Starting Load Kernel Module configfs...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.037596] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.171579] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.050615] systemd[1]: Starting Load Kernel Module efi_pstore...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.185287] systemd[1]: Starting Remount Root and Kernel File Systems...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.060762] systemd[1]: Starting Load Kernel Module fuse...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.191670] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.065470] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.205609] systemd[1]: Starting Coldplug All udev Devices...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.076117] systemd[1]: Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.223266] systemd[1]: Mounted Huge Pages File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.093766] systemd[1]: Starting Journal Service...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.230665] systemd[1]: Mounted POSIX Message Queue File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.102938] systemd[1]: Starting Load Kernel Modules...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.236350] systemd[1]: Mounted Kernel Debug File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.241873] systemd[1]: Mounted Kernel Trace File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.113794] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.248503] systemd[1]: Finished Create List of Static Device Nodes.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.123951] systemd[1]: Starting Remount Root and Kernel File Systems...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.261195] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.128931] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.144231] systemd[1]: Starting Coldplug All udev Devices...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.280547] systemd[1]: modprobe@configfs.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.155634] systemd[1]: Mounted Huge Pages File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.287241] systemd[1]: Finished Load Kernel Module configfs.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.160793] systemd[1]: Mounted POSIX Message Queue File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.293683] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.166835] systemd[1]: Mounted Kernel Debug File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.301769] systemd[1]: Finished Load Kernel Module efi_pstore.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.171650] systemd[1]: Mounted Kernel Trace File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.179675] systemd[1]: Finished Create List of Static Device Nodes.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.313163] systemd[1]: Mounting Kernel Configuration File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.192536] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.341531] systemd[1]: Mounted Kernel Configuration File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.216858] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.224457] systemd[1]: Finished Load Kernel Module efi_pstore.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.360884] EXT4-fs (vda): re-mounted 8bafdc3e-a9c6-4182-85ff-ada38abb7a0a.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.238222] systemd[1]: modprobe@configfs.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.372621] fuse: init (API version 7.41)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.375777] loop: module loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.244122] systemd[1]: Finished Load Kernel Module configfs.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.253516] systemd[1]: Mounting Kernel Configuration File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.384667] systemd[1]: Finished Remount Root and Kernel File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.388475] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.396228] systemd-journald[383]: Collecting audit messages is enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.403258] systemd[1]: Starting Load/Save OS Random Seed...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.407532] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.416574] systemd[1]: modprobe@fuse.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.285083] EXT4-fs (vda): re-mounted 13ea7290-ac56-4d64-9292-a47a8c0330df.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.289944] systemd[1]: Mounted Kernel Configuration File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.423884] systemd[1]: Finished Load Kernel Module fuse.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.431144] systemd[1]: Finished Load Kernel Modules.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.311614] loop: module loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.444465] systemd[1]: Starting Firewall...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.320524] systemd[1]: Finished Remount Root and Kernel File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.454156] systemd[1]: Starting Apply Kernel Variables...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.325437] fuse: init (API version 7.41)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.327797] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.339907] systemd-journald[386]: Collecting audit messages is enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.345993] systemd[1]: Starting Load/Save OS Random Seed...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.350821] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.361931] systemd[1]: modprobe@fuse.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.368930] systemd[1]: Finished Load Kernel Module fuse.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.375913] systemd[1]: Finished Load Kernel Modules.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.386997] systemd[1]: Starting Firewall...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.396071] systemd[1]: Starting Apply Kernel Variables...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.529953] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.543183] systemd[1]: Starting Create Static Device Nodes in /dev...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.549221] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.558428] systemd[1]: Finished Load/Save OS Random Seed.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.563949] systemd[1]: Reached target First Boot Complete.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.601217] systemd[1]: Finished Apply Kernel Variables.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.481202] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.491513] systemd[1]: Starting Create Static Device Nodes in /dev...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.505813] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.517768] systemd[1]: Finished Load/Save OS Random Seed.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.524084] systemd[1]: Reached target First Boot Complete.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.540554] systemd[1]: Finished Apply Kernel Variables.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.695279] systemd[1]: Finished Create Static Device Nodes in /dev.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.700772] systemd[1]: Reached target Preparation for Local File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.713217] systemd[1]: Starting Rule-based Manager for Device Events and Files...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.644649] systemd[1]: Finished Create Static Device Nodes in /dev.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.650831] systemd[1]: Reached target Preparation for Local File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.659582] systemd[1]: Starting Rule-based Manager for Device Events and Files...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.918198] systemd[1]: Started Journal Service.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.536091] systemd-modules-load[384]: Inserted module 'loop'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.547628] systemd-oomd[385]: [0;1;38:5:185mNo swap; memory pressure usage will be degraded[0m[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.563451] systemd-udevd[408]: Using default interface naming scheme 'v258'.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.578841] systemd[1]: Starting Flush Journal to Persistent Storage...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.856868] systemd[1]: Started Journal Service.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.460409] systemd-modules-load[387]: Inserted module 'loop'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.469715] systemd-oomd[388]: [0;1;38:5:185mNo swap; memory pressure usage will be degraded[0m[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.486466] systemd-udevd[411]: Using default interface naming scheme 'v258'.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.028358] systemd-journald[383]: Received client request to flush runtime journal.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.499796] systemd[1]: Starting Flush Journal to Persistent Storage...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.978046] systemd-journald[386]: Received client request to flush runtime journal.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.881422] systemd[1]: Finished Coldplug All udev Devices.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.893672] systemd[1]: Started Rule-based Manager for Device Events and Files.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.909190] systemd[1]: Mounting /run/wrappers...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.918944] systemd[1]: Mounting FUSE Control File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.925610] systemd[1]: Mounted FUSE Control File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.939283] systemd[1]: Finished Flush Journal to Persistent Storage.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.949339] systemd[1]: Mounted /run/wrappers.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.954875] systemd[1]: Reached target Local File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.966237] systemd[1]: Listening on Boot Entries Service Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.818781] systemd[1]: Finished Coldplug All udev Devices.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.975484] systemd[1]: Starting Create SUID/SGID Wrappers...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.839636] systemd[1]: Started Rule-based Manager for Device Events and Files.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.986370] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.002237] systemd[1]: Starting Save Transient machine-id to Disk...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.015841] systemd[1]: Starting Create System Files and Directories...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.093710] systemd[1]: Finished Create System Files and Directories.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.112560] systemd[1]: Starting Rebuild Journal Catalog...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.121667] systemd[1]: Starting Record System Boot/Shutdown in UTMP...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.858339] systemd[1]: Mounting /run/wrappers...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.133613] systemd[1]: Finished Save Transient machine-id to Disk.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.018499] systemd[1]: Mounting FUSE Control File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.033479] systemd[1]: Mounted FUSE Control File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.036347] systemd[1]: Mounted /run/wrappers.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.192405] systemd[1]: Finished Record System Boot/Shutdown in UTMP.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.051643] systemd[1]: Reached target Local File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.068537] systemd[1]: Listening on Boot Entries Service Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.075283] systemd[1]: Starting Create SUID/SGID Wrappers...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.082538] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.098976] systemd[1]: Starting Save Transient machine-id to Disk...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.107628] systemd[1]: Finished Flush Journal to Persistent Storage.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.266325] systemd[1]: Finished Rebuild Journal Catalog.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.115805] systemd[1]: Starting Create System Files and Directories...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.273898] systemd[1]: Starting Update is Completed...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.159306] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.170346] systemd[1]: Finished Save Transient machine-id to Disk.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.345340] systemd[1]: Finished Update is Completed.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.205457] systemd[1]: Finished Create System Files and Directories.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.215588] systemd[1]: Starting Rebuild Journal Catalog...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.230305] systemd[1]: Starting Record System Boot/Shutdown in UTMP...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.321532] systemd[1]: Finished Record System Boot/Shutdown in UTMP.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.384628] systemd[1]: Found device /dev/hvc0.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.393538] systemd[1]: Finished Rebuild Journal Catalog.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.404221] systemd[1]: Starting Update is Completed...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.572892] systemd[1]: Found device /dev/hvc0.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.482247] systemd[1]: Found device /dev/ttyS0.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.636364] systemd[1]: Found device /dev/ttyS0.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.490352] systemd[1]: Finished Update is Completed.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.668879] (udev-worker)[500]: [0;1;38:5:185meth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.[0m[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.691099] (udev-worker)[500]: Network interface NamePolicy= disabled on kernel command line.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.551654] (udev-worker)[480]: [0;1;38:5:185meth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.[0m[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.706479] (udev-worker)[503]: Network interface NamePolicy= disabled on kernel command line.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.572539] (udev-worker)[480]: Network interface NamePolicy= disabled on kernel command line.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.591744] (udev-worker)[474]: Network interface NamePolicy= disabled on kernel command line.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.810218] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.820974] systemd[1]: Finished Create SUID/SGID Wrappers.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.829956] systemd[1]: Reached target System Initialization.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.836792] systemd[1]: Started Discard unused filesystem blocks once a week.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.852425] systemd[1]: Started Daily Cleanup of Temporary Directories.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.870600] systemd[1]: Reached target Timer Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.884723] systemd[1]: Listening on D-Bus System Message Bus Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.904525] systemd[1]: Listening on Nix Daemon Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.918179] systemd[1]: Listening on Hostname Service Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.926823] systemd[1]: Reached target Socket Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.953805] systemd[1]: Reached target Basic System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.977183] systemd[1]: [0;1;39mSystem is tainted: support-ended[0m[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.841205] systemd[1]: Found device Virtio network device.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.994526] systemd[1]: Started backdoor.service.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.007564] systemd[1]: Starting Import lastlog data into lastlog2 database...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.029765] systemd[1]: Starting Name Service Cache Daemon (nsncd)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.054210] systemd[1]: Started Reset console on configuration changes.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.912192] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # connecting to host...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.924983] systemd[1]: Finished Create SUID/SGID Wrappers.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.070959] systemd[1]: Starting resolvconf update...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.932589] systemd[1]: Reached target System Initialization.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.086511] systemd[1]: Started Centjes docs site production Service.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.945635] systemd[1]: Started Discard unused filesystem blocks once a week.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.104408] nsncd[616]: Aug 06 21:20:18.366 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.968412] systemd[1]: Started Daily Cleanup of Temporary Directories.[0m
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: Guest shell says: b'Spawning backdoor root shell...\n'
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: connected to guest root shell
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: (connecting took 9.18 seconds)
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: (finished: waiting for the VM to finish booting, in 9.18 seconds)
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.129409] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.990880] systemd[1]: Reached target Timer Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.141371] systemd[1]: Starting D-Bus System Message Bus...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.150994] systemd[1]: Finished Firewall.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.004191] systemd[1]: Listening on D-Bus System Message Bus Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.159946] systemd[1]: Finished Import lastlog data into lastlog2 database.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.018593] systemd[1]: Listening on Nix Daemon Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.171734] dbus-daemon[621]: dbus[621]: Unknown username "systemd-timesync" in message bus configuration file[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.190723] systemd[1]: Started Name Service Cache Daemon (nsncd).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.043315] systemd[1]: Listening on Hostname Service Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.204656] systemd[1]: Reached target Host and Network Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.061985] systemd[1]: Reached target Socket Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.218768] systemd[1]: Reached target User and Group Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.088757] systemd[1]: Reached target Basic System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.233783] systemd[1]: Starting User Login Management...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.101373] systemd[1]: [0;1;39mSystem is tainted: support-ended[0m[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.245935] systemd[1]: Found device Virtio network device.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.119317] systemd[1]: Started backdoor.service.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.261664] systemd[1]: Started D-Bus System Message Bus.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # connecting to host...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.134881] systemd[1]: Starting Import lastlog data into lastlog2 database...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.151462] systemd[1]: Starting Name Service Cache Daemon (nsncd)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.164903] systemd[1]: Started Reset console on configuration changes.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.186235] nsncd[620]: Aug 06 21:20:18.615 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.351259] systemd-logind[646]: New seat seat0.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.206819] systemd[1]: Starting resolvconf update...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.223332] systemd[1]: Starting D-Bus System Message Bus...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.240122] systemd[1]: Finished Firewall.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.384767] systemd[1]: Started User Login Management.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.245804] systemd[1]: Finished Import lastlog data into lastlog2 database.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.257927] systemd[1]: Started Name Service Cache Daemon (nsncd).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.407801] systemd[1]: Stopped target Host and Network Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.267442] dbus-daemon[624]: dbus[624]: Unknown username "systemd-timesync" in message bus configuration file[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.420677] systemd[1]: Stopping Host and Network Name Lookups...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.285334] systemd[1]: Reached target Host and Network Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.295756] systemd[1]: Reached target User and Group Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.442653] systemd[1]: Stopped target User and Group Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.307232] systemd[1]: Starting User Login Management...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.455921] systemd[1]: Stopping User and Group Name Lookups...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.468956] systemd[1]: Starting linger-users.service...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.324827] systemd[1]: Started D-Bus System Message Bus.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.474939] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.485692] systemd[1]: nscd.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.495648] systemd[1]: Stopped Name Service Cache Daemon (nsncd).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.370257] systemd[1]: Stopped target Host and Network Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.509650] systemd[1]: Starting Name Service Cache Daemon (nsncd)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.527132] systemd[1]: linger-users.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.381289] systemd[1]: Stopping Host and Network Name Lookups...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.533304] systemd[1]: Finished linger-users.service.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.393494] systemd[1]: Stopped target User and Group Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.401567] systemd[1]: Stopping User and Group Name Lookups...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.410638] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.551648] systemd[1]: Started Name Service Cache Daemon (nsncd).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.418651] systemd[1]: nscd.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.563370] systemd[1]: Reached target Host and Network Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.427974] systemd[1]: Stopped Name Service Cache Daemon (nsncd).[    8.833250] mousedev: PS/2 mouse device common for all mice[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.435640] systemd[1]: Starting Name Service Cache Daemon (nsncd)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.576550] nsncd[699]: Aug 06 21:20:18.839 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.444359] systemd-logind[650]: New seat seat0.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.592957] systemd[1]: Reached target User and Group Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.452788] systemd[1]: Started User Login Management.[    8.854566] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.466978] systemd[1]: Starting linger-users.service...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.620982] systemd[1]: Finished resolvconf update.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.629951] systemd[1]: Reached target Preparation for Network.[    9.017811] mousedev: PS/2 mouse device common for all mice[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.887412] ACPI: button: Power Button [PWRF][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.636752] systemd[1]: Starting DHCP Client...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.500391] systemd[1]: Started Name Service Cache Daemon (nsncd).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.645580] systemd[1]: Starting Address configuration of eth1...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.508153] nsncd[687]: Aug 06 21:20:18.938 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.522917] systemd[1]: Reached target Host and Network Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.536362] systemd[1]: Reached target User and Group Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.544796] systemd[1]: linger-users.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.551307] systemd[1]: Finished linger-users.service.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.572189] systemd[1]: Finished resolvconf update.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.578637] systemd[1]: Reached target Preparation for Network.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.120838] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.586671] systemd[1]: Starting DHCP Client...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.594665] systemd[1]: Starting Address configuration of eth1...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.154474] ACPI: button: Power Button [PWRF][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.777878] network-addresses-eth1-start[730]: adding address 192.168.1.2/24... done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.049739] rtc_cmos 00:05: RTC can wake from S4[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.800654] network-addresses-eth1-start[730]: adding address 2001:db8:1::2/64... done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.828403] systemd[1]: Finished Address configuration of eth1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.837650] systemd[1]: Starting Networking Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.094594] parport_pc 00:03: reported by Plug and Play ACPI[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.234846] rtc_cmos 00:05: RTC can wake from S4[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.104482] Floppy drive(s): fd0 is 2.88M AMI BIOS[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.119564] rtc_cmos 00:05: registered as rtc0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.880943] dhcpcd[737]: dhcpcd-10.2.4 starting[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.140628] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.275887] Floppy drive(s): fd0 is 2.88M AMI BIOS[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.897481] dhcpcd[749]: dev: loaded udev[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.750758] network-addresses-eth1-start[717]: adding address 192.168.1.1/24... done[    9.156712] rtc_cmos 00:05: setting system clock to 2026-08-06T21:20:18 UTC (1786051218)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.760392] network-addresses-eth1-start[717]: adding address 2001:db8:1::1/64... done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.298332] parport_pc 00:03: reported by Plug and Play ACPI[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.170547] FDC 0 is a S82078B[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.303796] rtc_cmos 00:05: registered as rtc0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.174420] systemd-journald[386]: Time jumped backwards, rotating.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.309273] rtc_cmos 00:05: setting system clock to 2026-08-06T21:20:19 UTC (1786051219)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.317828] 8021q: 802.1Q VLAN Support v1.8[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.322386] FDC 0 is a S82078B[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.323941] 8021q: adding VLAN 0 to HW filter on device eth1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.331822] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.225038] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.365622] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.835580] systemd[1]: Finished Address configuration of eth1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.858669] systemd[1]: Starting Networking Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.866530] dhcpcd[723]: dhcpcd-10.2.4 starting[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.878289] dhcpcd[737]: dev: loaded udev[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.292019] 8021q: 802.1Q VLAN Support v1.8[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.295712] 8021q: adding VLAN 0 to HW filter on device eth1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.453889] cfg80211: Loading compiled-in X.509 certificates for regulatory database[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.336067] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.484604] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.491589] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.494759] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.501826] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.505065] cfg80211: failed to load regulatory.db[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.137914] systemd[1]: Finished Networking Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.147314] systemd[1]: Reached target Network.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.150938] systemd[1]: Starting Permit User Sessions...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.416856] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.420459] cfg80211: Loading compiled-in X.509 certificates for regulatory database[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.564670] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.444897] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input4[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.457558] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.460635] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.468078] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.471212] cfg80211: failed to load regulatory.db[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.219629] systemd[1]: Finished Permit User Sessions.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.230377] systemd[1]: Started Getty on tty1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.234444] systemd[1]: Reached target Login Prompts.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.086592] systemd[1]: Finished Networking Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.093929] systemd[1]: Reached target Network.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.102246] systemd[1]: Starting Permit User Sessions...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.509073] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.643127] 8021q: adding VLAN 0 to HW filter on device eth0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.646898] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input4[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.264764] dhcpcd[749]: eth0: waiting for carrier[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.656290] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.665584] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.698428] cryptd: max_cpu_qlen set to 1000[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.169662] systemd[1]: Finished Permit User Sessions.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.545892] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.718700] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.546482] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.735673] AES CTR mode by8 optimization enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.565620] cryptd: max_cpu_qlen set to 1000[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.597428] 8021q: adding VLAN 0 to HW filter on device eth0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.743304] Console: switching to colour dummy device 80x25[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.607655] Console: switching to colour dummy device 80x25[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.788539] [drm] Found bochs VGA, ID 0xb0c5.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.789697] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.617731] AES CTR mode by8 optimization enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.197955] dhcpcd[737]: eth0: waiting for carrier[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.279661] systemd[1]: Started Getty on tty1.[    9.681206] [drm] Found bochs VGA, ID 0xb0c5.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.682236] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.283900] dhcpcd[737]: [0;1;31mlibudev: received NULL device[0m[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.287558] dhcpcd[737]: libudev: received NULL device[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.436362] systemd[1]: Starting Virtual Console Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.291474] systemd[1]: Reached target Login Prompts.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.833937] [drm] Found EDID data blob.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.837428] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.338726] systemd[1]: Starting Virtual Console Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.756713] [drm] Found EDID data blob.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.361152] systemd-logind[650]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.772558] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.242645] dhcpcd[737]: eth0: carrier acquired[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.846282] fbcon: bochs-drmdrmfb (fb0) is primary device[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.741921] Console: switching to colour frame buffer device 160x50[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.893247] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.325572] dhcpcd[749]: eth0: carrier acquired[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.511206] dhcpcd[749]: DUID 00:01:00:01:32:07:b7:14:52:54:00:12:34:56[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.513918] dhcpcd[749]: eth0: IAID 00:12:34:56[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.515948] dhcpcd[749]: eth0: adding address fe80::5054:ff:fe12:3456[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.786155] fbcon: bochs-drmdrmfb (fb0) is primary device[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.665770] Console: switching to colour frame buffer device 160x50[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.818180] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.258174] dhcpcd[737]: DUID 00:01:00:01:32:07:b7:14:52:54:00:12:34:56[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.423568] dhcpcd[737]: eth0: IAID 00:12:34:56[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.425353] dhcpcd[737]: eth0: adding address fe80::5054:ff:fe12:3456[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.427766] dhcpcd[737]: eth0: soliciting a DHCP lease[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.871539] NET: Registered PF_PACKET protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.488839] dhcpcd[737]: eth0: offered 10.0.2.15 from 10.0.2.2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.494893] dhcpcd[737]: eth0: probing address 10.0.2.15/24[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.013566] kvm_amd: TSC scaling supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.014605] kvm_amd: Nested Virtualization enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.015782] kvm_amd: Nested Paging enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.016931] kvm_amd: LBR virtualization supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.644568] systemd-logind[650]: Watching system buttons on /dev/input/event2 (Power Button)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.062168] kvm_amd: Virtual VMLOAD VMSAVE supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.063346] kvm_amd: Virtual GIF supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.067919] ppdev: user-space parallel port driver[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.808532] systemd-logind[646]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.823314] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.837409] systemd-logind[646]: Watching system buttons on /dev/input/event2 (Power Button)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.245826] ppdev: user-space parallel port driver[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.250778] kvm_amd: TSC scaling supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.715772] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.251780] kvm_amd: Nested Virtualization enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.253105] kvm_amd: Nested Paging enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.254100] kvm_amd: LBR virtualization supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.286888] kvm_amd: Virtual VMLOAD VMSAVE supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.288099] kvm_amd: Virtual GIF supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.779375] systemd-logind[650]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.203768] EDAC MC: Ver: 3.0.0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.997669] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.848906] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.008319] systemd[1]: Stopped Virtual Console Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.862790] systemd[1]: Stopped Virtual Console Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.012485] systemd[1]: Starting Virtual Console Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.869939] systemd[1]: Starting Virtual Console Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.021295] systemd-logind[646]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.909651] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.922531] systemd[1]: Stopped Virtual Console Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.925126] systemd[1]: Starting Virtual Console Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.457765] EDAC MC: Ver: 3.0.0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.084825] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.095904] systemd[1]: Stopped Virtual Console Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.099099] systemd[1]: Starting Virtual Console Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.335415] systemd[1]: Finished Virtual Console Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.505946] systemd[1]: Finished Virtual Console Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.695349] dhcpcd[749]: eth0: soliciting a DHCP lease[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.102194] NET: Registered PF_PACKET protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.725772] dhcpcd[749]: eth0: offered 10.0.2.15 from 10.0.2.2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.729360] dhcpcd[749]: eth0: probing address 10.0.2.15/24[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.815681] dhcpcd[749]: eth0: soliciting an IPv6 router[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.818424] dhcpcd[749]: eth0: Router Advertisement from fe80::2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.820842] dhcpcd[749]: eth0: adding address fec0::5054:ff:fe12:3456/64[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.822853] dhcpcd[749]: eth0: adding route to fec0::/64[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.824640] dhcpcd[749]: eth0: adding default route via fe80::2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.338494] dhcpcd[737]: eth0: soliciting an IPv6 router[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.340664] dhcpcd[737]: eth0: Router Advertisement from fe80::2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.342750] dhcpcd[737]: eth0: adding address fec0::5054:ff:fe12:3456/64[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.344781] dhcpcd[737]: eth0: adding route to fec0::/64[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.346496] dhcpcd[737]: eth0: adding default route via fe80::2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   15.207809] dhcpcd[737]: eth0: leased 10.0.2.15 for 86400 seconds[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   15.210657] dhcpcd[737]: eth0: adding route to 10.0.2.0/24[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   15.212829] dhcpcd[737]: eth0: adding default route via 10.0.2.2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   15.331946] systemd[1]: Started DHCP Client.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   15.334967] systemd[1]: Reached target Multi-User System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   15.338474] systemd[1]: Startup finished in 3.845s (kernel) + 11.491s (userspace) = 15.336s.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   16.559091] dhcpcd[749]: eth0: leased 10.0.2.15 for 86400 seconds[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   16.561726] dhcpcd[749]: eth0: adding route to 10.0.2.0/24[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   16.565377] dhcpcd[749]: eth0: adding default route via 10.0.2.2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   16.682969] systemd[1]: Started DHCP Client.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   16.689409] systemd[1]: Reached target Multi-User System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   16.691294] systemd[1]: Startup finished in 3.990s (kernel) + 12.698s (userspace) = 16.688s.[0m
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: (finished: waiting for unit default.target, in 18.25 seconds)
[2m[vm-test-run-centjes-e2e-test] [mclient: [1m[32mwaiting for unit default.target[0m
[2m[vm-test-run-centjes-e2e-test] [mclient: [1m[32mwaiting for the VM to finish booting[0m
[2m[vm-test-run-centjes-e2e-test] [mclient: Guest shell says: b'Spawning backdoor root shell...\n'
[2m[vm-test-run-centjes-e2e-test] [mclient: connected to guest root shell
[2m[vm-test-run-centjes-e2e-test] [mclient: (connecting took 0.00 seconds)
[2m[vm-test-run-centjes-e2e-test] [mclient: (finished: waiting for the VM to finish booting, in 0.00 seconds)
[2m[vm-test-run-centjes-e2e-test] [mclient: (finished: waiting for unit default.target, in 0.12 seconds)
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: [1m[32mwaiting for unit centjes-docs-site-production.service[0m
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: (finished: waiting for unit centjes-docs-site-production.service, in 0.05 seconds)
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: [1m[32mwaiting for unit default.target[0m
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: (finished: waiting for unit default.target, in 0.05 seconds)
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: [1m[32mwaiting for TCP port 8001 on localhost[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Connection to localhost (127.0.0.1) 8001 port [tcp/vcom-tunnel] succeeded![0m
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: (finished: waiting for TCP port 8001 on localhost, in 0.09 seconds)
[2m[vm-test-run-centjes-e2e-test] [mclient: [1m[32mmust succeed: curl docsserver:8001[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient #   % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient #                                  Dload  Upload   Total   Spent    Left  Speed[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   17.752787] centjes-docs-site-production-start[631]: 192.168.1.1 - - [06/Aug/2026:21:20:28 +0000] "GET / HTTP/1.1" 200 3595 "" "curl/8.17.0"[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient #   0     0   0     0   0     0     0     0  --:--:-- --:--:-- --:--:--     0100  3595 100  3595   0     0 53355     0  --:--:-- --:--:-- --:--:-- 54469[0m
[2m[vm-test-run-centjes-e2e-test] [mclient: (finished: must succeed: curl docsserver:8001, in 0.16 seconds)
[2m[vm-test-run-centjes-e2e-test] [m(finished: run the VM test script, in 19.22 seconds)
[2m[vm-test-run-centjes-e2e-test] [mtest script finished in 19.34s
[2m[vm-test-run-centjes-e2e-test] [m[1m[32mcleanup[0m
[2m[vm-test-run-centjes-e2e-test] [mkill machine (pid 31)
[2m[vm-test-run-centjes-e2e-test] [mqemu-system-x86_64: terminating on signal 15 from pid 6 (/nix/store/jj6jldlw37r8yy9kc1smrax9dhnjm2x4-python3-3.13.9/bin/python3.13)
[2m[vm-test-run-centjes-e2e-test] [mkill machine (pid 9)
[2m[vm-test-run-centjes-e2e-test] [mqemu-system-x86_64: terminating on signal 15 from pid 6 (/nix/store/jj6jldlw37r8yy9kc1smrax9dhnjm2x4-python3-3.13.9/bin/python3.13)
[2m[vm-test-run-centjes-e2e-test] [mkill vlan (pid 7)
[2m[vm-test-run-centjes-e2e-test] [m(finished: cleanup, in 0.02 seconds)
[2m[vm-test-run-centjes-e2e-test:post-build] [m[2mUploading to cachix cache "centjes": /nix/store/xxn3rlimd8z5cjg9jxyv34nydi7lhmx1-vm-test-run-centjes-e2e-test[m
[2m[vm-test-run-centjes-e2e-test:post-build] [m[2mPushing 1 paths (0 are already present) using zstd to cache centjes ⏳[m
[2m[vm-test-run-centjes-e2e-test:post-build] [m[2m[m
[2m[vm-test-run-centjes-e2e-test:post-build] [m[2mPushing /nix/store/xxn3rlimd8z5cjg9jxyv34nydi7lhmx1-vm-test-run-centjes-e2e-test (96.00 B)[m
[2m[vm-test-run-centjes-e2e-test:post-build] [m[2m[m
[2m[vm-test-run-centjes-e2e-test:post-build] [m[2mAll done.[m
[2m[vm-test-run-centjes-e2e-test:post-build] [m[2mUploading to the NixCI staging cache: /nix/store/xxn3rlimd8z5cjg9jxyv34nydi7lhmx1-vm-test-run-centjes-e2e-test[m
[2m[vm-test-run-centjes-e2e-test:post-build] [m[2mwarning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead[m
[2m[vm-test-run-centjes-e2e-test:post-build] [m[2mcopying 1 paths...[m
[2m[vm-test-run-centjes-e2e-test:post-build] [m[2mcopying path '/nix/store/xxn3rlimd8z5cjg9jxyv34nydi7lhmx1-vm-test-run-centjes-e2e-test' to 'https://cache.staging.nix-ci.com'...[m
[2m[vm-test-run-centjes-e2e-test:post-build] [m[2mwarning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead[m
[2m[vm-test-run-centjes-e2e-test:post-build] [m[2mcopying 0 paths...[m
[32mUploaded vm-test-run-centjes-e2e-test in 2.6s[m
[2mProgress: 1 of 2 built, 1 of 1 downloaded from cache[m
[1;34mBuilt vm-test-run-centjes-e2e-test in 23.1s[m
[2mProgress: 2 of 2 built, 1 of 1 downloaded from cache[m
/nix/store/xxn3rlimd8z5cjg9jxyv34nydi7lhmx1-vm-test-run-centjes-e2e-test
Build succeeded.[m