$ /nix/store/vzx1mi9c0xfadmsm9dhd83d005cb1qs9-coreutils-9.8/bin/timeout --kill-after=15s 7200s /nix/store/99b1z08awpxj8b6mzggn59gp1shljnff-nix-2.34.5/bin/nix --extra-experimental-features nix-command --extra-experimental-features flakes --log-format internal-json build --no-link git+https://github.com/NorfairKing/centjes?ref=refs%2Fpull%2F2%2Fmerge&rev=d826b4fcc9fd37022acc53b94edda6d94580d29b#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
[1;34mBuilding etc[m
[2m[post-build-hook] [m[2mUploading paths from nix-ci to cachix cache "centjes" /nix/store/k1zvssljx90qfjqbvcq8gbgdg5ss639r-etc[m
[2m[post-build-hook] [m[2mPushing 2 paths (545 are already present) using zstd to cache centjes ⏳[m
[2m[post-build-hook] [m[2m[m
[2m[post-build-hook] [m[2mPushing /nix/store/k1zvssljx90qfjqbvcq8gbgdg5ss639r-etc (31.56 KiB)[m
[2m[post-build-hook] [m[2mPushing /nix/store/10zz99ch74vmfkmjjl9khlqp3iy9sg8v-fc-cache (5.77 KiB)[m
[2m[post-build-hook] [m[2m[m
[2m[post-build-hook] [m[2mAll done.[m
[2m[post-build-hook] [m[2mUploading paths from nix-ci to the NixCI staging cache /nix/store/k1zvssljx90qfjqbvcq8gbgdg5ss639r-etc[m
[2m[post-build-hook] [m[2mwarning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead[m
[2m[post-build-hook] [m[2mcopying 1 paths...[m
[2m[post-build-hook] [m[2mcopying path '/nix/store/k1zvssljx90qfjqbvcq8gbgdg5ss639r-etc' to 'https://cache.staging.nix-ci.com'...[m
[2m[post-build-hook] [m[2mwarning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead[m
[2m[post-build-hook] [m[2mcopying 1 paths...[m
[2m[post-build-hook] [m[2mcopying path '/nix/store/xr10q6mnnpmmbnr4j7rhixsrb2ih670f-etc.drv' to 'https://cache.staging.nix-ci.com'...[m
[2mProgress: 1 of 8 built[m
[1;34mBuilding nixos-system-docsserver-test[m
[2m[post-build-hook] [m[2mUploading paths from nix-ci to cachix cache "centjes" /nix/store/b4ylhqbb1k81nwvff9ifybzbhdcjb9l7-nixos-system-docsserver-test[m
[2m[post-build-hook] [m[2mPushing 1 paths (568 are already present) using zstd to cache centjes ⏳[m
[2m[post-build-hook] [m[2m[m
[2m[post-build-hook] [m[2mPushing /nix/store/b4ylhqbb1k81nwvff9ifybzbhdcjb9l7-nixos-system-docsserver-test (17.26 KiB)[m
[2m[post-build-hook] [m[2m[m
[2m[post-build-hook] [m[2mAll done.[m
[2m[post-build-hook] [m[2mUploading paths from nix-ci to the NixCI staging cache /nix/store/b4ylhqbb1k81nwvff9ifybzbhdcjb9l7-nixos-system-docsserver-test[m
[2m[post-build-hook] [m[2mwarning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead[m
[2m[post-build-hook] [m[2mcopying 1 paths...[m
[2m[post-build-hook] [m[2mcopying path '/nix/store/b4ylhqbb1k81nwvff9ifybzbhdcjb9l7-nixos-system-docsserver-test' to 'https://cache.staging.nix-ci.com'...[m
[2m[post-build-hook] [m[2mwarning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead[m
[2m[post-build-hook] [m[2mcopying 1 paths...[m
[2m[post-build-hook] [m[2mcopying path '/nix/store/imsyrp5rn5qgc5ivqvlz9syskv7j1laz-nixos-system-docsserver-test.drv' to 'https://cache.staging.nix-ci.com'...[m
[2mProgress: 2 of 8 built[m
[1;34mBuilding closure-info[m
[2m[closure-info] [mstructuredAttrs is enabled
[2m[post-build-hook] [m[2mUploading paths from nix-ci to cachix cache "centjes" /nix/store/ngsdybz5cfd5mx5iawx565xpmyvxhj1z-closure-info[m
[2m[post-build-hook] [m[2mPushing 1 paths (569 are already present) using zstd to cache centjes ⏳[m
[2m[post-build-hook] [m[2m[m
[2m[post-build-hook] [m[2mPushing /nix/store/ngsdybz5cfd5mx5iawx565xpmyvxhj1z-closure-info (228.66 KiB)[m
[2m[post-build-hook] [m[2m[m
[2m[post-build-hook] [m[2mAll done.[m
[2m[post-build-hook] [m[2mUploading paths from nix-ci to the NixCI staging cache /nix/store/ngsdybz5cfd5mx5iawx565xpmyvxhj1z-closure-info[m
[2m[post-build-hook] [m[2mwarning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead[m
[2m[post-build-hook] [m[2mcopying 1 paths...[m
[2m[post-build-hook] [m[2mcopying path '/nix/store/ngsdybz5cfd5mx5iawx565xpmyvxhj1z-closure-info' to 'https://cache.staging.nix-ci.com'...[m
[2m[post-build-hook] [m[2mwarning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead[m
[2m[post-build-hook] [m[2mcopying 1 paths...[m
[2m[post-build-hook] [m[2mcopying path '/nix/store/lkv7imkjz1phzvqmixr3zlkj3fkvh6hv-closure-info.drv' to 'https://cache.staging.nix-ci.com'...[m
[2mProgress: 3 of 8 built[m
[1;34mBuilding run-nixos-vm[m
[2m[post-build-hook] [m[2mUploading paths from nix-ci to cachix cache "centjes" /nix/store/51d860flx72l22ic2aqhw1gba77dq6z5-run-nixos-vm[m
[2m[post-build-hook] [m[2mPushing 1 paths (580 are already present) using zstd to cache centjes ⏳[m
[2m[post-build-hook] [m[2m[m
[2m[post-build-hook] [m[2mPushing /nix/store/51d860flx72l22ic2aqhw1gba77dq6z5-run-nixos-vm (2.94 KiB)[m
[2m[post-build-hook] [m[2m[m
[2m[post-build-hook] [m[2mAll done.[m
[2m[post-build-hook] [m[2mUploading paths from nix-ci to the NixCI staging cache /nix/store/51d860flx72l22ic2aqhw1gba77dq6z5-run-nixos-vm[m
[2m[post-build-hook] [m[2mwarning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead[m
[2m[post-build-hook] [m[2mcopying 1 paths...[m
[2m[post-build-hook] [m[2mcopying path '/nix/store/51d860flx72l22ic2aqhw1gba77dq6z5-run-nixos-vm' to 'https://cache.staging.nix-ci.com'...[m
[2m[post-build-hook] [m[2mwarning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead[m
[2m[post-build-hook] [m[2mcopying 1 paths...[m
[2m[post-build-hook] [m[2mcopying path '/nix/store/ddcqp6csvxjdx2qh2jmdlndm8yn1091d-run-nixos-vm.drv' to 'https://cache.staging.nix-ci.com'...[m
[2mProgress: 4 of 8 built[m
[1;34mBuilding nixos-vm[m
[2m[post-build-hook] [m[2mUploading paths from nix-ci to cachix cache "centjes" /nix/store/ncy3cfb3av7xxdlfz8x9di2x8bllk9r5-nixos-vm[m
[2m[post-build-hook] [m[2mPushing 1 paths (581 are already present) using zstd to cache centjes ⏳[m
[2m[post-build-hook] [m[2m[m
[2m[post-build-hook] [m[2mPushing /nix/store/ncy3cfb3av7xxdlfz8x9di2x8bllk9r5-nixos-vm (776.00 B)[m
[2m[post-build-hook] [m[2m[m
[2m[post-build-hook] [m[2mAll done.[m
[2m[post-build-hook] [m[2mUploading paths from nix-ci to the NixCI staging cache /nix/store/ncy3cfb3av7xxdlfz8x9di2x8bllk9r5-nixos-vm[m
[2m[post-build-hook] [m[2mwarning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead[m
[2m[post-build-hook] [m[2mcopying 1 paths...[m
[2m[post-build-hook] [m[2mcopying path '/nix/store/ncy3cfb3av7xxdlfz8x9di2x8bllk9r5-nixos-vm' to 'https://cache.staging.nix-ci.com'...[m
[2m[post-build-hook] [m[2mwarning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead[m
[2m[post-build-hook] [m[2mcopying 1 paths...[m
[2m[post-build-hook] [m[2mcopying path '/nix/store/a5zbl6xv4zk53h8n9iragir5ay367jbr-nixos-vm.drv' to 'https://cache.staging.nix-ci.com'...[m
[2mProgress: 5 of 8 built[m
[1;34mBuilding nixos-test-driver-centjes-e2e-test[m
[2m[nixos-test-driver-centjes-e2e-test] [mRunning type check (enable/disable: config.skipTypeCheck)
[2m[nixos-test-driver-centjes-e2e-test] [mSee https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck
[2m[nixos-test-driver-centjes-e2e-test] [mSuccess: no issues found in 1 source file
[2m[nixos-test-driver-centjes-e2e-test] [madditionally exposed symbols:
[2m[nixos-test-driver-centjes-e2e-test] [m    ,
[2m[nixos-test-driver-centjes-e2e-test] [m    ,
[2m[nixos-test-driver-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[nixos-test-driver-centjes-e2e-test] [mLinting test script (enable/disable: config.skipLint)
[2m[nixos-test-driver-centjes-e2e-test] [mSee https://nixos.org/manual/nixos/stable/#test-opt-skipLint
[2m[post-build-hook] [m[2mUploading paths from nix-ci to cachix cache "centjes" /nix/store/37c0smrk6sidn7q1dydyfklxm5x48li1-nixos-test-driver-centjes-e2e-test[m
[2m[post-build-hook] [m[2mPushing 1 paths (633 are already present) using zstd to cache centjes ⏳[m
[2m[post-build-hook] [m[2m[m
[2m[post-build-hook] [m[2mPushing /nix/store/37c0smrk6sidn7q1dydyfklxm5x48li1-nixos-test-driver-centjes-e2e-test (1.84 KiB)[m
[2m[post-build-hook] [m[2m[m
[2m[post-build-hook] [m[2mAll done.[m
[2m[post-build-hook] [m[2mUploading paths from nix-ci to the NixCI staging cache /nix/store/37c0smrk6sidn7q1dydyfklxm5x48li1-nixos-test-driver-centjes-e2e-test[m
[2m[post-build-hook] [m[2mwarning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead[m
[2m[post-build-hook] [m[2mcopying 1 paths...[m
[2m[post-build-hook] [m[2mcopying path '/nix/store/37c0smrk6sidn7q1dydyfklxm5x48li1-nixos-test-driver-centjes-e2e-test' to 'https://cache.staging.nix-ci.com'...[m
[2m[post-build-hook] [m[2mwarning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead[m
[2m[post-build-hook] [m[2mcopying 1 paths...[m
[2m[post-build-hook] [m[2mcopying path '/nix/store/wh0h0hfm7000xf5hqh1j3sa9wl1v71zs-nixos-test-driver-centjes-e2e-test.drv' to 'https://cache.staging.nix-ci.com'...[m
[2mProgress: 6 of 8 built[m
[1;34mBuilding vm-test-run-centjes-e2e-test[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] [m[2mdocsserver # Formatting '/build/vm-state-docsserver/tmp.EODLZvN0xR', fmt=raw size=1073741824[0m
[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: a58cf4d2-0219-4a8c-84a7-269dfc704e6c[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] [mdocsserver: QEMU running (pid 9)
[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] [mclient: starting vm
[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] [mclient: QEMU running (pid 31)
[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] [m[2mclient # Formatting '/build/vm-state-client/tmp.TbPRvkqN9h', fmt=raw size=1073741824[0m
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: [1m[32mwaiting for unit default.target[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 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: c00e5177-af61-4791-a5a3-b08482f95c0f[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[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 # 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[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[2mclient # Probing EDD (edd=off to disable)... ok[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/b4ylhqbb1k81nwvff9ifybzbhdcjb9l7-nixos-system-docsserver-test/init regInfo=/nix/store/ngsdybz5cfd5mx5iawx565xpmyvxhj1z-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 411338543 cycles[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000001] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000004] tsc: Detected 3399.996 MHz processor[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000684] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000887] 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.000895] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002619] found SMP MP-table at [mem 0x000f5470-0x000f547f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002628] Using GB pages for direct mapping[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002702] RAMDISK: [mem 0x3f3cd000-0x3ffcffff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002719] ACPI: Early table checksum verification disabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002722] ACPI: RSDP 0x00000000000F5290 000014 (v00 BOCHS )[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002724] ACPI: RSDT 0x000000003FFE23D9 000034 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002728] ACPI: FACP 0x000000003FFE228D 000074 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002731] ACPI: DSDT 0x000000003FFE0040 00224D (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002733] ACPI: FACS 0x000000003FFE0000 000040[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002734] ACPI: APIC 0x000000003FFE2301 000078 (v03 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002736] ACPI: HPET 0x000000003FFE2379 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002737] ACPI: WAET 0x000000003FFE23B1 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002738] ACPI: Reserving FACP table memory at [mem 0x3ffe228d-0x3ffe2300][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002739] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe228c][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002740] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002740] ACPI: Reserving APIC table memory at [mem 0x3ffe2301-0x3ffe2378][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002740] ACPI: Reserving HPET table memory at [mem 0x3ffe2379-0x3ffe23b0][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.002741] ACPI: Reserving WAET table memory at [mem 0x3ffe23b1-0x3ffe23d8][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003496] No NUMA configuration found[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003497] Faking a node at [mem 0x0000000000000000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003499] NODE_DATA(0) allocated [mem 0x3ffd58c0-0x3ffdadff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003513] Zone ranges:[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003514]   DMA      [mem 0x0000000000001000-0x0000000000ffffff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003515]   DMA32    [mem 0x0000000001000000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003515]   Normal   empty[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003516]   Device   empty[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003516] Movable zone start for each node[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003517] Early memory node ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003517]   node   0: [mem 0x0000000000001000-0x000000000009efff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003518]   node   0: [mem 0x0000000000100000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003519] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003642] On node 0, zone DMA: 1 pages in unavailable ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003654] On node 0, zone DMA: 97 pages in unavailable ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.006130] On node 0, zone DMA32: 37 pages in unavailable ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007700] ACPI: PM-Timer IO Port: 0x608[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007723] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007805] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007806] 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.007808] 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.007808] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # 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.007809] 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.007810] 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.007812] ACPI: Using ACPI (MADT) for SMP configuration information[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007812] ACPI: HPET id: 0x8086a201 base: 0xfed00000[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007814] TSC deadline timer available[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.007817] CPU topo: Max. logical packages:   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.007818] CPU topo: Max. logical dies:       1[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.007818] CPU topo: Max. dies per package:   1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007821] CPU topo: Max. threads per core:   1[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[2mdocsserver # [    0.007822] CPU topo: Num. cores per package:     1[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[2mdocsserver # [    0.007822] CPU topo: Num. threads per package:   1[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[2mdocsserver # [    0.007822] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs[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[2mdocsserver # [    0.007848] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()[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.007900] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff][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[2mdocsserver # [    0.007901] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff][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.007902] [mem 0x40000000-0xfeffbfff] available for PCI devices[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.007903] Booting paravirtualized kernel on KVM[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[2mclient # [    0.000000] SMBIOS 2.8 present.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.007904] 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] 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.011171] 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] DMI: Memory slots populated: 1/1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.011412] percpu: Embedded 116 pages/cpu s294912 r65536 d114688 u2097152[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.011482] kvm-guest: PV spinlocks disabled, single CPU[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[2mclient # [    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] kvm-clock: using sched offset of 414300246 cycles[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000001] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000003] tsc: Detected 3399.996 MHz processor[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.011483] 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/b4ylhqbb1k81nwvff9ifybzbhdcjb9l7-nixos-system-docsserver-test/init regInfo=/nix/store/ngsdybz5cfd5mx5iawx565xpmyvxhj1z-closure-info/registration console=ttyS0,115200n8 console=tty0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000660] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000877] 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.011550] Unknown kernel command line parameters "regInfo=/nix/store/ngsdybz5cfd5mx5iawx565xpmyvxhj1z-closure-info/registration", will be passed to user space.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000885] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.011564] random: crng init done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002588] found SMP MP-table at [mem 0x000f5470-0x000f547f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.011616] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002595] Using GB pages for direct mapping[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002724] RAMDISK: [mem 0x3f3cd000-0x3ffcffff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.011646] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002740] ACPI: Early table checksum verification disabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.011679] Fallback order for Node 0: 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002742] ACPI: RSDP 0x00000000000F5290 000014 (v00 BOCHS )[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.011681] Built 1 zonelists, mobility grouping on.  Total pages: 262009[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.011682] Policy zone: DMA32[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002744] ACPI: RSDT 0x000000003FFE23D9 000034 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.011884] mem auto-init: stack:all(zero), heap alloc:on, heap free:off[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002747] ACPI: FACP 0x000000003FFE228D 000074 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.013564] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.013776] allocated 2097152 bytes of page_ext[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002750] ACPI: DSDT 0x000000003FFE0040 00224D (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.013786] ftrace: allocating 46208 entries in 181 pages[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002751] ACPI: FACS 0x000000003FFE0000 000040[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.021198] ftrace: allocated 181 pages with 5 groups[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.021689] Dynamic Preempt: voluntary[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002752] ACPI: APIC 0x000000003FFE2301 000078 (v03 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.021882] rcu: Preemptible hierarchical RCU implementation.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.021883] rcu: 	RCU event tracing is enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002754] ACPI: HPET 0x000000003FFE2379 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.021883] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002755] ACPI: WAET 0x000000003FFE23B1 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.021884] 	Trampoline variant of Tasks RCU enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002756] ACPI: Reserving FACP table memory at [mem 0x3ffe228d-0x3ffe2300][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.021884] 	Rude variant of Tasks RCU enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.021885] 	Tracing variant of Tasks RCU enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002757] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe228c][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.021885] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002757] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.021886] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002758] ACPI: Reserving APIC table memory at [mem 0x3ffe2301-0x3ffe2378][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002758] ACPI: Reserving HPET table memory at [mem 0x3ffe2379-0x3ffe23b0][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.021891] 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.002759] ACPI: Reserving WAET table memory at [mem 0x3ffe23b1-0x3ffe23d8][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.021893] 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.003511] No NUMA configuration found[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003512] Faking a node at [mem 0x0000000000000000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.021894] 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.003514] NODE_DATA(0) allocated [mem 0x3ffd58c0-0x3ffdadff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.025166] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003527] Zone ranges:[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.025413] rcu: srcu_init: Setting srcu_struct sizes based on contention.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003527]   DMA      [mem 0x0000000000001000-0x0000000000ffffff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003528]   DMA32    [mem 0x0000000001000000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003529]   Normal   empty[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.025474] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003529]   Device   empty[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.038909] Console: colour VGA+ 80x25[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003530] Movable zone start for each node[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.038912] printk: legacy console [tty0] enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003530] Early memory node ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.141931] printk: legacy console [ttyS0] enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003531]   node   0: [mem 0x0000000000001000-0x000000000009efff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.393543] ACPI: Core revision 20240827[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003532]   node   0: [mem 0x0000000000100000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003532] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.395778] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003690] On node 0, zone DMA: 1 pages in unavailable ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.400240] APIC: Switch to symmetric I/O mode setup[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003737] On node 0, zone DMA: 97 pages in unavailable ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.006245] On node 0, zone DMA32: 37 pages in unavailable ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.403507] x2apic enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007836] ACPI: PM-Timer IO Port: 0x608[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007859] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.405854] APIC: Switched APIC routing to: physical x2apic[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007942] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007944] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007945] 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.412288] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007945] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007946] 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.415170] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007947] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007949] ACPI: Using ACPI (MADT) for SMP configuration information[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.419970] Calibrating delay loop (skipped) preset value.. 6799.99 BogoMIPS (lpj=3399996)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007949] ACPI: HPET id: 0x8086a201 base: 0xfed00000[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007951] TSC deadline timer available[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007954] CPU topo: Max. logical packages:   1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.423170] x86/cpu: User Mode Instruction Prevention (UMIP) activated[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007955] CPU topo: Max. logical dies:       1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007955] CPU topo: Max. dies per package:   1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.425394] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007958] CPU topo: Max. threads per core:   1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007958] CPU topo: Num. cores per package:     1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.426969] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007959] CPU topo: Num. threads per package:   1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007959] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.428979] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007984] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.430970] Spectre V2 : Mitigation: Retpolines[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008035] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.432969] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008036] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008037] [mem 0x40000000-0xfeffbfff] available for PCI devices[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.434969] Spectre V2 : Enabling Restricted Speculation for firmware calls[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008038] Booting paravirtualized kernel on KVM[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008039] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.436970] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011303] 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[2mdocsserver # [    0.438970] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011560] percpu: Embedded 116 pages/cpu s294912 r65536 d114688 u2097152[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011629] kvm-guest: PV spinlocks disabled, single CPU[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.440969] active return thunk: srso_alias_return_thunk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.442969] Speculative Return Stack Overflow: Mitigation: Safe RET[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.444969] Transient Scheduler Attacks: Forcing mitigation on in a VM[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011630] 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.445969] Transient Scheduler Attacks: Vulnerable: Clear CPU buffers attempted, no microcode[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.448052] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011697] 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.011715] random: crng init done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011768] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011816] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011849] Fallback order for Node 0: 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011851] Built 1 zonelists, mobility grouping on.  Total pages: 262009[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011852] Policy zone: DMA32[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.449969] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.012056] mem auto-init: stack:all(zero), heap alloc:on, heap free:off[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.013735] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.451969] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.013964] allocated 2097152 bytes of page_ext[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.013975] ftrace: allocating 46208 entries in 181 pages[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.453969] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.021327] ftrace: allocated 181 pages with 5 groups[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.021834] Dynamic Preempt: voluntary[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.455969] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.022028] rcu: Preemptible hierarchical RCU implementation.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.022029] rcu: 	RCU event tracing is enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.457969] x86/fpu: xstate_offset[9]:  832, xstate_sizes[9]:    8[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.022029] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.022030] 	Trampoline variant of Tasks RCU enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.459969] 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.022031] 	Rude variant of Tasks RCU enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.022031] 	Tracing variant of Tasks RCU enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.022032] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.022032] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.022037] 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.022038] 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.022039] 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.025358] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.025601] rcu: srcu_init: Setting srcu_struct sizes based on contention.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.025655] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.039249] Console: colour VGA+ 80x25[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.486029] Freeing SMP alternatives memory: 40K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.039251] printk: legacy console [tty0] enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.143459] printk: legacy console [ttyS0] enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.486970] pid_max: default: 32768 minimum: 301[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.394005] ACPI: Core revision 20240827[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.489005] LSM: initializing lsm=capability,landlock,yama,bpf[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.491036] landlock: Up and running.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.396198] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.491969] Yama: becoming mindful.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.400520] APIC: Switch to symmetric I/O mode setup[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.494039] LSM support for eBPF active[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.403737] x2apic enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.494993] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.406060] APIC: Switched APIC routing to: physical x2apic[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.496970] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.499311] 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.412410] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.501218] Performance Events: Fam17h+ core perfctr, AMD PMU driver.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.502984] ... version:                0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.415303] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.503977] ... bit width:              48[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.504977] ... generic registers:      6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.420108] Calibrating delay loop (skipped) preset value.. 6799.99 BogoMIPS (lpj=3399996)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.505977] ... value mask:             0000ffffffffffff[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.423158] x86/cpu: User Mode Instruction Prevention (UMIP) activated[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.506977] ... max period:             00007fffffffffff[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.507977] ... fixed-purpose events:   0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.424565] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.508977] ... event mask:             000000000000003f[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.426108] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.510047] signal: max sigframe size: 3376[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.511005] rcu: Hierarchical SRCU implementation.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.428117] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.430109] Spectre V2 : Mitigation: Retpolines[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.511977] rcu: 	Max phase no-delay instances is 400.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.431108] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.515938] smp: Bringing up secondary CPUs ...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.433108] Spectre V2 : Enabling Restricted Speculation for firmware calls[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.516984] smp: Brought up 1 node, 1 CPU[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.517978] smpboot: Total of 1 processors activated (6799.99 BogoMIPS)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.435109] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.437109] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.519266] 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.439108] active return thunk: srso_alias_return_thunk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.521055] devtmpfs: initialized[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.440108] Speculative Return Stack Overflow: Mitigation: Safe RET[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.522020] x86/mm: Memory block size: 128MB[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.442108] Transient Scheduler Attacks: Forcing mitigation on in a VM[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.523409] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.444108] Transient Scheduler Attacks: Vulnerable: Clear CPU buffers attempted, no microcode[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.524979] futex hash table entries: 256 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.446189] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.526021] pinctrl core: initialized pinctrl subsystem[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.527250] PM: RTC time: 13:54:56, date: 2026-04-27[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.528487] NET: Registered PF_NETLINK/PF_ROUTE protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.530116] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.448108] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.531985] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.450108] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.533980] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.452108] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.535982] audit: initializing netlink subsys (disabled)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.454108] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.537023] audit: type=2000 audit(1777298096.474:1): state=initialized audit_enabled=0 res=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.456108] x86/fpu: xstate_offset[9]:  832, xstate_sizes[9]:    8[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.539073] thermal_sys: Registered thermal governor 'bang_bang'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.458108] 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.539075] thermal_sys: Registered thermal governor 'step_wise'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.539977] thermal_sys: Registered thermal governor 'user_space'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.540988] cpuidle: using governor menu[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.544687] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.546358] PCI: Using configuration type 1 for base access[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.547976] PCI: Using configuration type 1 for extended access[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.549097] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.483178] Freeing SMP alternatives memory: 40K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.484109] pid_max: default: 32768 minimum: 301[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.485145] LSM: initializing lsm=capability,landlock,yama,bpf[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.487198] landlock: Up and running.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.488108] Yama: becoming mindful.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.490248] LSM support for eBPF active[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.491127] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.570194] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.493110] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.571977] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.495480] smpboot: CPU0: AMD Ryzen 9 5950X 16-Core Processor (family: 0x19, model: 0x21, stepping: 0x0)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.572977] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.497322] Performance Events: Fam17h+ core perfctr, AMD PMU driver.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.573976] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.499123] ... version:                0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.500116] ... bit width:              48[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.501116] ... generic registers:      6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.579578] ACPI: Added _OSI(Module Device)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.502116] ... value mask:             0000ffffffffffff[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.580977] ACPI: Added _OSI(Processor Device)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.503116] ... max period:             00007fffffffffff[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.581977] ACPI: Added _OSI(Processor Aggregator Device)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.504116] ... fixed-purpose events:   0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.505116] ... event mask:             000000000000003f[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.584535] ACPI: 1 ACPI AML tables successfully acquired and loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.506187] signal: max sigframe size: 3376[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.507145] rcu: Hierarchical SRCU implementation.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.587178] ACPI: Interpreter enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.587989] ACPI: PM: (supports S0 S3 S4 S5)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.508117] rcu: 	Max phase no-delay instances is 400.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.588977] ACPI: Using IOAPIC for interrupt routing[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.512029] smp: Bringing up secondary CPUs ...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.589988] 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.513123] smp: Brought up 1 node, 1 CPU[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.591976] PCI: Using E820 reservations for host bridge windows[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.514118] smpboot: Total of 1 processors activated (6799.99 BogoMIPS)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.593118] ACPI: Enabled 2 GPEs in block 00 to 0F[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.515411] Memory: 962140K/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[2mdocsserver # [    0.597465] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.517192] devtmpfs: initialized[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.518160] x86/mm: Memory block size: 128MB[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.598980] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.601268] acpiphp: Slot [3] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.519528] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.602012] acpiphp: Slot [4] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.521118] futex hash table entries: 256 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.603014] acpiphp: Slot [5] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.604032] acpiphp: Slot [6] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.522161] pinctrl core: initialized pinctrl subsystem[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.605012] acpiphp: Slot [7] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.523413] PM: RTC time: 13:54:57, date: 2026-04-27[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.606011] acpiphp: Slot [8] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.607013] acpiphp: Slot [9] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.524613] NET: Registered PF_NETLINK/PF_ROUTE protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.608011] acpiphp: Slot [10] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.526239] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.609012] acpiphp: Slot [11] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.610012] acpiphp: Slot [12] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.528124] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.611014] acpiphp: Slot [13] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.612022] acpiphp: Slot [14] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.530120] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.613011] acpiphp: Slot [15] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.532122] audit: initializing netlink subsys (disabled)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.614013] acpiphp: Slot [16] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.615011] acpiphp: Slot [17] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.533142] audit: type=2000 audit(1777298096.603:1): state=initialized audit_enabled=0 res=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.616014] acpiphp: Slot [18] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.617027] acpiphp: Slot [19] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.535212] thermal_sys: Registered thermal governor 'bang_bang'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.618012] acpiphp: Slot [20] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.535213] thermal_sys: Registered thermal governor 'step_wise'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.619012] acpiphp: Slot [21] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.536117] thermal_sys: Registered thermal governor 'user_space'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.620015] acpiphp: Slot [22] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.537126] cpuidle: using governor menu[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.621012] acpiphp: Slot [23] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.622013] acpiphp: Slot [24] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.623011] acpiphp: Slot [25] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.540871] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.624011] acpiphp: Slot [26] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.542336] PCI: Using configuration type 1 for base access[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.625011] acpiphp: Slot [27] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.543116] PCI: Using configuration type 1 for extended access[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.626013] acpiphp: Slot [28] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.627012] acpiphp: Slot [29] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.544237] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.628012] acpiphp: Slot [30] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.629013] acpiphp: Slot [31] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.630002] PCI host bridge to bus 0000:00[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.630981] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.631977] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.632977] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.634977] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.636977] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.565262] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.638977] pci_bus 0000:00: root bus resource [bus 00-ff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.567133] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.640420] 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.568116] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.569132] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.643064] 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.646506] 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.574241] ACPI: Added _OSI(Module Device)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.575116] ACPI: Added _OSI(Processor Device)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.576117] ACPI: Added _OSI(Processor Aggregator Device)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.651418] pci 0000:00:01.1: BAR 4 [io  0xc1e0-0xc1ef][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.578682] ACPI: 1 ACPI AML tables successfully acquired and loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.654472] pci 0000:00:01.1: BAR 0 [io  0x01f0-0x01f7]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.581316] ACPI: Interpreter enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.582129] ACPI: PM: (supports S0 S3 S4 S5)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.655977] pci 0000:00:01.1: BAR 1 [io  0x03f6]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.583116] ACPI: Using IOAPIC for interrupt routing[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.656977] pci 0000:00:01.1: BAR 2 [io  0x0170-0x0177]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.657977] pci 0000:00:01.1: BAR 3 [io  0x0376]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.584127] 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.586116] PCI: Using E820 reservations for host bridge windows[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.659314] 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.587263] ACPI: Enabled 2 GPEs in block 00 to 0F[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.663533] pci 0000:00:01.2: BAR 4 [io  0xc100-0xc11f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.591692] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.667523] 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.593121] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.595452] acpiphp: Slot [3] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.596154] acpiphp: Slot [4] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.670282] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.597155] acpiphp: Slot [5] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.672158] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.598154] acpiphp: Slot [6] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.599152] acpiphp: Slot [7] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.600152] acpiphp: Slot [8] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.674525] 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.601153] acpiphp: Slot [9] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.602152] acpiphp: Slot [10] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.677594] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.603173] acpiphp: Slot [11] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.604187] acpiphp: Slot [12] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.605155] acpiphp: Slot [13] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.681019] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.606153] acpiphp: Slot [14] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.607151] acpiphp: Slot [15] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.608153] acpiphp: Slot [16] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.609151] acpiphp: Slot [17] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.686599] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.610151] acpiphp: Slot [18] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.611153] acpiphp: Slot [19] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.688122] 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.612155] acpiphp: Slot [20] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.613154] acpiphp: Slot [21] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.614153] acpiphp: Slot [22] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.690998] 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.615153] acpiphp: Slot [23] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.616154] acpiphp: Slot [24] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.693977] pci 0000:00:03.0: BAR 0 [io  0xc120-0xc13f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.617153] acpiphp: Slot [25] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.618153] acpiphp: Slot [26] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.695977] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.619151] acpiphp: Slot [27] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.620153] acpiphp: Slot [28] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.621153] acpiphp: Slot [29] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.622153] acpiphp: Slot [30] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.699977] pci 0000:00:03.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.623156] acpiphp: Slot [31] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.624144] PCI host bridge to bus 0000:00[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.703505] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.625120] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.626117] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.707523] 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.627117] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.709977] pci 0000:00:04.0: BAR 0 [io  0xc140-0xc15f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.629117] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.711977] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.631117] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.633117] pci_bus 0000:00: root bus resource [bus 00-ff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.634615] 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.716977] pci 0000:00:04.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.637416] 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.721602] 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.640660] 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.723977] pci 0000:00:05.0: BAR 0 [io  0xc080-0xc0bf][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.725977] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.646120] pci 0000:00:01.1: BAR 4 [io  0xc1e0-0xc1ef][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.648842] pci 0000:00:01.1: BAR 0 [io  0x01f0-0x01f7]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.730562] pci 0000:00:05.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.650117] pci 0000:00:01.1: BAR 1 [io  0x03f6]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.651117] pci 0000:00:01.1: BAR 2 [io  0x0170-0x0177]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.735580] 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.652116] pci 0000:00:01.1: BAR 3 [io  0x0376]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.737977] pci 0000:00:06.0: BAR 0 [io  0xc160-0xc17f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.653493] 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.739978] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.658075] pci 0000:00:01.2: BAR 4 [io  0xc100-0xc11f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.744507] pci 0000:00:06.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.661926] 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.664419] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.749497] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.666140] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.751977] pci 0000:00:07.0: BAR 0 [io  0xc180-0xc19f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.753875] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.667675] 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.670703] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.758385] pci 0000:00:07.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.674149] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.763465] 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.765977] pci 0000:00:08.0: BAR 0 [io  0xc000-0xc07f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.680005] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.767977] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.681275] 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.683858] 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.771977] pci 0000:00:08.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.686116] pci 0000:00:03.0: BAR 0 [io  0xc120-0xc13f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.688091] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.776617] 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.778977] pci 0000:00:09.0: BAR 0 [io  0xc1a0-0xc1bf][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.693116] pci 0000:00:03.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.780896] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.694967] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.784554] pci 0000:00:09.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.698720] 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.701116] pci 0000:00:04.0: BAR 0 [io  0xc140-0xc15f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.703116] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.786955] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.707075] pci 0000:00:04.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.790604] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.712086] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.793358] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.714116] pci 0000:00:05.0: BAR 0 [io  0xc080-0xc0bf][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.796977] pci 0000:00:0a.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.716004] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.801515] 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.719988] pci 0000:00:05.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.803977] pci 0000:00:0b.0: BAR 0 [io  0xc0c0-0xc0ff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.805947] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.724877] 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.727116] pci 0000:00:06.0: BAR 0 [io  0xc160-0xc17f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.809555] pci 0000:00:0b.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.728969] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.814732] 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.733117] pci 0000:00:06.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.816977] pci 0000:00:0c.0: BAR 0 [io  0xc1c0-0xc1df][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.818858] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.738409] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.741116] pci 0000:00:07.0: BAR 0 [io  0xc180-0xc19f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.822501] pci 0000:00:0c.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.743678] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.828640] ACPI: PCI: Interrupt link LNKA configured for IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.830175] ACPI: PCI: Interrupt link LNKB configured for IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.747716] pci 0000:00:07.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.832168] ACPI: PCI: Interrupt link LNKC configured for IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.834157] ACPI: PCI: Interrupt link LNKD configured for IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.753159] 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.836078] ACPI: PCI: Interrupt link LNKS configured for IRQ 9[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.837324] iommu: Default domain type: Translated[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.756116] pci 0000:00:08.0: BAR 0 [io  0xc000-0xc07f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.838977] iommu: DMA domain TLB invalidation policy: lazy mode[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.840001] ACPI: bus type USB registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.758116] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.840998] usbcore: registered new interface driver usbfs[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.841986] usbcore: registered new interface driver hub[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.762116] pci 0000:00:08.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.842986] usbcore: registered new device driver usb[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.844263] NetLabel: Initializing[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.844977] NetLabel:  domain hash size = 128[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.845977] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.766797] 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.846994] NetLabel:  unlabeled traffic allowed by default[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.847977] PCI: Using ACPI for IRQ routing[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.769667] pci 0000:00:09.0: BAR 0 [io  0xc1a0-0xc1bf][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.849726] pci 0000:00:02.0: vgaarb: setting as boot VGA device[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.772117] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.849967] pci 0000:00:02.0: vgaarb: bridge control possible[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.849967] 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.849979] vgaarb: loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.776637] pci 0000:00:09.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.851047] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.851977] hpet0: 3 comparators, 64-bit 100.000000 MHz counter[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.857002] clocksource: Switched to clocksource kvm-clock[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.860346] VFS: Disk quotas dquot_6.6.0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.779117] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.862271] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.865470] pnp: PnP ACPI init[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.867647] pnp: PnP ACPI: found 6 devices[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.783735] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.786532] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.875658] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.790562] pci 0000:00:0a.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.879720] clocksource: Switched to clocksource acpi_pm[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.882292] NET: Registered PF_INET protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.795279] 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.884820] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.798116] pci 0000:00:0b.0: BAR 0 [io  0xc0c0-0xc0ff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.800009] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.897700] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.803992] pci 0000:00:0b.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.901630] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.905263] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.808553] 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.908957] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.811116] pci 0000:00:0c.0: BAR 0 [io  0xc1c0-0xc1df][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.912411] TCP: Hash tables configured (established 8192 bind 8192)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.813028] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.915497] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.919082] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.817004] pci 0000:00:0c.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.922251] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.925674] NET: Registered PF_UNIX/PF_LOCAL protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.928358] NET: Registered PF_XDP protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.821885] ACPI: PCI: Interrupt link LNKA configured for IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.930830] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.823343] ACPI: PCI: Interrupt link LNKB configured for IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.933816] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.825349] ACPI: PCI: Interrupt link LNKC configured for IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.936824] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.827343] ACPI: PCI: Interrupt link LNKD configured for IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.940065] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.829251] ACPI: PCI: Interrupt link LNKS configured for IRQ 9[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.830506] iommu: Default domain type: Translated[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.943292] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.832116] iommu: DMA domain TLB invalidation policy: lazy mode[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.946714] pci 0000:00:01.0: PIIX3: Enabling Passive Release[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.833142] ACPI: bus type USB registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.949461] pci 0000:00:00.0: Limiting direct PCI/PCI transfers[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.834137] usbcore: registered new interface driver usbfs[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.953727] ACPI: \_SB_.LNKD: Enabled at IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.835140] usbcore: registered new interface driver hub[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.836126] usbcore: registered new device driver usb[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.957970] PCI: CLS 0 bytes, default 64[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.837441] NetLabel: Initializing[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.960156] Trying to unpack rootfs image as initramfs...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.838116] NetLabel:  domain hash size = 128[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.839117] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.840149] NetLabel:  unlabeled traffic allowed by default[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.964683] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.841117] PCI: Using ACPI for IRQ routing[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.842918] pci 0000:00:02.0: vgaarb: setting as boot VGA device[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.843106] pci 0000:00:02.0: vgaarb: bridge control possible[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.843106] 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.843119] vgaarb: loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.844189] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.845116] hpet0: 3 comparators, 64-bit 100.000000 MHz counter[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.849161] clocksource: Switched to clocksource kvm-clock[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.994453] Initialise system trusted keyrings[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.853333] VFS: Disk quotas dquot_6.6.0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.855276] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.858487] pnp: PnP ACPI init[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.999688] workingset: timestamp_bits=40 max_order=18 bucket_order=0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.860595] pnp: PnP ACPI: found 6 devices[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.868462] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.872455] clocksource: Switched to clocksource acpi_pm[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.875068] NET: Registered PF_INET protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.877750] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.025693] Key type asymmetric registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.030647] Asymmetric key parser 'x509' registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.889787] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.893663] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.037664] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.897260] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.900875] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.046104] Freeing initrd memory: 12300K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.904249] TCP: Hash tables configured (established 8192 bind 8192)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.048267] io scheduler mq-deadline registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.050517] io scheduler kyber registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.907261] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.910771] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.052971] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.913829] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.056337] 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.917072] NET: Registered PF_UNIX/PF_LOCAL protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.060990] Linux agpgart interface v0.103[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.919827] NET: Registered PF_XDP protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.063103] ACPI: bus type drm_connector registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.922199] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.065833] usbcore: registered new interface driver usbserial_generic[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.925073] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.068948] usbserial: USB Serial support registered for generic[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.927967] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.071809] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.931194] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.075319] drop_monitor: Initializing network drop monitor service[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.934400] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.078396] NET: Registered PF_INET6 protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.937756] pci 0000:00:01.0: PIIX3: Enabling Passive Release[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.081389] Segment Routing with IPv6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.940605] pci 0000:00:00.0: Limiting direct PCI/PCI transfers[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.083329] In-situ OAM (IOAM) with IPv6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.085720] IPI shorthand broadcast: enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.944722] ACPI: \_SB_.LNKD: Enabled at IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.089881] registered taskstats version 1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.948897] PCI: CLS 0 bytes, default 64[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.092105] Loading compiled-in X.509 certificates[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.951116] Trying to unpack rootfs image as initramfs...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.099065] Demotion targets for Node 0: null[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.955652] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.101339] Key type .fscrypt registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.103365] Key type fscrypt-provisioning registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.105924] PM:   Magic number: 6:287:939[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.108679] RAS: Correctable Errors collector initialized.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.114051] Unstable clock detected, switching default tracing clock to "global"[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.114051] If you want to keep using the local clock, then add:[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.114051]   "trace_clock=local"[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.114051] on the kernel command line[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.122903] clk: Disabling unused clocks[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.124870] PM: genpd: Disabling unused power domains[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.984922] Initialise system trusted keyrings[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.128323] Freeing unused decrypted memory: 2028K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.131008] Freeing unused kernel image (initmem) memory: 3408K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.990647] workingset: timestamp_bits=40 max_order=18 bucket_order=0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.133843] Write protecting the kernel read-only data: 30720k[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.137051] Freeing unused kernel image (rodata/data gap) memory: 1756K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.015585] Key type asymmetric registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.020719] Asymmetric key parser 'x509' registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.027726] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.035650] io scheduler mq-deadline registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.178393] x86/mm: Checked W+X mappings: passed, no W+X pages found.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.037834] io scheduler kyber registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.181456] Run /init as init process[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.041838] Freeing initrd memory: 12300K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.044089] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.047459] 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.052088] Linux agpgart interface v0.103[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.054210] ACPI: bus type drm_connector registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.056899] usbcore: registered new interface driver usbserial_generic[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.059990] usbserial: USB Serial support registered for generic[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.062821] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.066320] drop_monitor: Initializing network drop monitor service[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.069381] NET: Registered PF_INET6 protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.072397] Segment Routing with IPv6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.074322] In-situ OAM (IOAM) with IPv6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.076692] IPI shorthand broadcast: enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.080816] registered taskstats version 1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.082987] Loading compiled-in X.509 certificates[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.224370] 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.089844] Demotion targets for Node 0: null[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.092103] Key type .fscrypt registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.094145] Key type fscrypt-provisioning registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.096685] PM:   Magic number: 6:287:939[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.099228] RAS: Correctable Errors collector initialized.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.104688] Unstable clock detected, switching default tracing clock to "global"[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.104688] If you want to keep using the local clock, then add:[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.104688]   "trace_clock=local"[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.104688] on the kernel command line[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.113920] clk: Disabling unused clocks[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.115931] PM: genpd: Disabling unused power domains[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.119335] Freeing unused decrypted memory: 2028K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.122042] Freeing unused kernel image (initmem) memory: 3408K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.124864] Write protecting the kernel read-only data: 30720k[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.127965] Freeing unused kernel image (rodata/data gap) memory: 1756K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.169005] x86/mm: Checked W+X mappings: passed, no W+X pages found.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.172041] Run /init as init process[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.214608] 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.383351] 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.406964] uhci_hcd 0000:00:01.2: UHCI Host Controller[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.411223] serio: i8042 KBD port at 0x60,0x64 irq 1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.414012] SCSI subsystem initialized[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.418629] 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.422649] ACPI: \_SB_.LNKC: Enabled at IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.427631] serio: i8042 AUX port at 0x60,0x64 irq 12[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.437340] uhci_hcd 0000:00:01.2: detected 2 ports[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.446492] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.453250] ACPI: \_SB_.LNKA: Enabled at IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.457781] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.12[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.461594] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.465015] usb usb1: Product: UHCI Host Controller[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.467472] usb usb1: Manufacturer: Linux 6.12.62 uhci_hcd[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.470189] usb usb1: SerialNumber: 0000:00:01.2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.476746] scsi host0: ata_piix[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.480480] ACPI: \_SB_.LNKB: Enabled at IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.484824] scsi host1: ata_piix[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.489434] 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.493012] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.504806] hub 1-0:1.0: USB hub found[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.511715] hub 1-0:1.0: 2 ports detected[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.368997] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.386859] SCSI subsystem initialized[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.399994] ACPI: \_SB_.LNKC: Enabled at IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.403769] serio: i8042 KBD port at 0x60,0x64 irq 1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.410215] uhci_hcd 0000:00:01.2: UHCI Host Controller[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.415569] serio: i8042 AUX port at 0x60,0x64 irq 12[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.421573] 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.437862] uhci_hcd 0000:00:01.2: detected 2 ports[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.442968] ACPI: \_SB_.LNKA: Enabled at IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.448274] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.455162] scsi host0: ata_piix[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.458466] ACPI: \_SB_.LNKB: Enabled at IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.461069] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.12[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.464883] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.468332] usb usb1: Product: UHCI Host Controller[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.470788] usb usb1: Manufacturer: Linux 6.12.62 uhci_hcd[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.473572] usb usb1: SerialNumber: 0000:00:01.2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.476039] scsi host1: ata_piix[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.477887] 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.481585] 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.496898] hub 1-0:1.0: USB hub found[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.502603] hub 1-0:1.0: 2 ports detected[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.653696] ata2: found unknown device (class 0)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.657093] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.662114] 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.732636] usb 1-1: new full-speed USB device number 2 using uhci_hcd[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.741815] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.768353] virtio_blk virtio5: 1/0/0 default/read/poll queues[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.637156] ata2: found unknown device (class 0)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.641138] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.645951] 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.794031] 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.808260] netfs: FS-Cache loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.812980] 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.816164] cdrom: Uniform CD-ROM driver Revision: 3.20[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.830356] 9pnet: Installing 9P2000 support[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.722954] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.729584] usb 1-1: new full-speed USB device number 2 using uhci_hcd[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.752041] virtio_blk virtio5: 1/0/0 default/read/poll queues[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.910953] 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.914880] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.918392] usb 1-1: Product: QEMU USB Tablet[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.776396] 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.920642] usb 1-1: Manufacturer: QEMU[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.922604] usb 1-1: SerialNumber: 28754-0000:00:01.2-1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.782845] netfs: FS-Cache loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.796736] 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.799886] cdrom: Uniform CD-ROM driver Revision: 3.20[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.803726] 9pnet: Installing 9P2000 support[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.936664] hid: raw HID events driver (C) Jiri Kosina[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.969279] usbcore: registered new interface driver usbhid[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.972016] usbhid: USB HID core driver[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.977760] 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 # [    1.983287] 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.905667] 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.909569] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.912961] usb 1-1: Product: QEMU USB Tablet[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.915171] usb 1-1: Manufacturer: QEMU[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.917169] usb 1-1: SerialNumber: 28754-0000:00:01.2-1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.936237] hid: raw HID events driver (C) Jiri Kosina[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.959826] usbcore: registered new interface driver usbhid[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.962603] usbhid: USB HID core driver[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.106485] EXT4-fs (vda): mounted filesystem a58cf4d2-0219-4a8c-84a7-269dfc704e6c r/w with ordered data mode. Quota mode: none.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.966382] 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 # [    1.971875] 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.127153] 9p: Installing v9fs 9p2000 file system support[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.095901] EXT4-fs (vda): mounted filesystem c00e5177-af61-4791-a5a3-b08482f95c0f r/w with ordered data mode. Quota mode: none.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.116855] 9p: Installing v9fs 9p2000 file system support[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.350673] EXT4-fs (vda): re-mounted a58cf4d2-0219-4a8c-84a7-269dfc704e6c.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.355496] booting system configuration /nix/store/b4ylhqbb1k81nwvff9ifybzbhdcjb9l7-nixos-system-docsserver-test[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.358625] EXT4-fs (vda): re-mounted c00e5177-af61-4791-a5a3-b08482f95c0f.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.363337] booting system configuration /nix/store/001k9r4jinw78ih5x5hxggb82ynlbkqn-nixos-system-client-test[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    3.874340] systemd[1]: Inserted module 'autofs4'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    3.918803] 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[2mdocsserver # [    3.933535] systemd[1]: Detected virtualization kvm.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    3.936147] systemd[1]: Detected architecture x86-64.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    3.938785] systemd[1]: Detected first boot.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    3.944049] systemd[1]: Initializing machine ID from random generator.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    3.967006] systemd[1]: Hostname set to <docsserver>.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    3.906790] systemd[1]: Inserted module 'autofs4'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    4.075253] systemd[1]: bpf-restrict-fs: LSM BPF program attached[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    3.951920] 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 # [    3.967685] systemd[1]: Detected virtualization kvm.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    3.970307] systemd[1]: Detected architecture x86-64.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    3.972858] systemd[1]: Detected first boot.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    3.978129] systemd[1]: Initializing machine ID from random generator.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    4.003278] systemd[1]: Hostname set to <client>.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    4.208732] systemd[1]: Applying preset policy.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    4.113775] systemd[1]: bpf-restrict-fs: LSM BPF program attached[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    4.261704] systemd[1]: Applying preset policy.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    4.739854] systemd[1]: Populated /etc with preset unit settings.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    4.805075] systemd[1]: Populated /etc with preset unit settings.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.116107] systemd[1]: /etc/systemd/system/centjes-docs-site-production.service:3: Invalid URL, ignoring: /nix/store/afmrim8qnza0a65snqgh9m574hcav0m7-settings-check[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.220305] systemd[1]: Queued start job for default target Multi-User System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.250254] systemd[1]: Created slice Slice /system/getty.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.253273] systemd[1]: Created slice Slice /system/modprobe.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.256296] systemd[1]: Created slice User and Session Slice.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.259104] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.262270] systemd[1]: Started Forward Password Requests to Wall Directory Watch.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.265507] systemd[1]: Expecting device /dev/hvc0...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.267707] systemd[1]: Expecting device /dev/ttyS0...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.269780] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.272676] systemd[1]: Reached target Local Encrypted Volumes.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.274992] systemd[1]: Reached target Virtual Machines and Containers.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.277748] systemd[1]: Reached target Path Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.279761] systemd[1]: Reached target Remote File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.282033] systemd[1]: Reached target Slice Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.284163] systemd[1]: Reached target Swaps.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.289668] systemd[1]: Listening on Process Core Dump Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.294282] systemd[1]: Listening on Credential Encryption/Decryption.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.297269] systemd[1]: Listening on Journal Audit Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.299766] systemd[1]: Listening on Journal Socket (/dev/log).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.302287] systemd[1]: Listening on Journal Sockets.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.304796] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.307696] 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 # [    5.311859] systemd[1]: Listening on udev Control Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.314205] systemd[1]: Listening on udev Kernel Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.320291] systemd[1]: Mounting Huge Pages File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.325697] systemd[1]: Mounting POSIX Message Queue File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.332514] systemd[1]: Mounting Kernel Debug File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.343731] systemd[1]: Mounting Kernel Trace File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.359116] systemd[1]: Starting Create List of Static Device Nodes...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.365906] 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 # [    5.382792] systemd[1]: Starting Load Kernel Module configfs...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.389227] 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 # [    5.399402] systemd[1]: Starting Load Kernel Module efi_pstore...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.407468] systemd[1]: Starting Load Kernel Module fuse...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.412211] 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 # [    5.417076] 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 # [    5.429643] systemd[1]: Starting Journal Service...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.298231] systemd[1]: Queued start job for default target Multi-User System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.441700] systemd[1]: Starting Load Kernel Modules...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.452716] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.461836] systemd[1]: Starting Remount Root and Kernel File Systems...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.467104] 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 # [    5.329254] systemd[1]: Created slice Slice /system/getty.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.332306] systemd[1]: Created slice Slice /system/modprobe.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.335296] systemd[1]: Created slice User and Session Slice.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.338157] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.482162] systemd[1]: Starting Coldplug All udev Devices...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.341364] systemd[1]: Started Forward Password Requests to Wall Directory Watch.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.344646] systemd[1]: Expecting device /dev/hvc0...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.346688] systemd[1]: Expecting device /dev/ttyS0...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.348762] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.492234] systemd[1]: Mounted Huge Pages File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.351730] systemd[1]: Reached target Local Encrypted Volumes.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.354124] systemd[1]: Reached target Virtual Machines and Containers.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.497256] systemd[1]: Mounted POSIX Message Queue File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.356777] systemd[1]: Reached target Path Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.358736] systemd[1]: Reached target Remote File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.502412] systemd[1]: Mounted Kernel Debug File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.360964] systemd[1]: Reached target Slice Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.363012] systemd[1]: Reached target Swaps.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.506857] systemd[1]: Mounted Kernel Trace File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.368671] systemd[1]: Listening on Process Core Dump Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.373625] systemd[1]: Listening on Credential Encryption/Decryption.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.517018] systemd[1]: Finished Create List of Static Device Nodes.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.376815] systemd[1]: Listening on Journal Audit Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.379162] systemd[1]: Listening on Journal Socket (/dev/log).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.381797] systemd[1]: Listening on Journal Sockets.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.384219] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.387084] 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 # [    5.530805] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.391267] systemd[1]: Listening on udev Control Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.393769] systemd[1]: Listening on udev Kernel Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.538965] systemd[1]: modprobe@configfs.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.400208] systemd[1]: Mounting Huge Pages File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.547080] systemd[1]: Finished Load Kernel Module configfs.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.405707] systemd[1]: Mounting POSIX Message Queue File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.554017] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.413490] systemd[1]: Mounting Kernel Debug File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.561990] systemd[1]: Finished Load Kernel Module efi_pstore.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.425960] systemd[1]: Mounting Kernel Trace File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.571515] systemd[1]: Mounting Kernel Configuration File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.440252] systemd[1]: Starting Create List of Static Device Nodes...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.449595] 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 # [    5.597393] systemd[1]: Mounted Kernel Configuration File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.473773] systemd[1]: Starting Load Kernel Module configfs...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.618843] EXT4-fs (vda): re-mounted a58cf4d2-0219-4a8c-84a7-269dfc704e6c.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.476238] 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 # [    5.622398] loop: module loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.624765] systemd-journald[383]: Collecting audit messages is enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.484960] systemd[1]: Starting Load Kernel Module efi_pstore...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.631791] fuse: init (API version 7.41)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.494820] systemd[1]: Starting Load Kernel Module fuse...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.640237] systemd[1]: Finished Load Kernel Modules.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.499897] 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 # [    5.646398] systemd[1]: Finished Remount Root and Kernel File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.509010] 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 # [    5.657791] systemd[1]: Starting Firewall...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.521731] systemd[1]: Starting Journal Service...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.662482] 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 # [    5.531703] systemd[1]: Starting Load Kernel Modules...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.679381] systemd[1]: Starting Load/Save OS Random Seed...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.543161] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.690141] systemd[1]: Starting Apply Kernel Variables...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.694691] 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 # [    5.555738] systemd[1]: Starting Remount Root and Kernel File Systems...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.704142] systemd[1]: modprobe@fuse.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.561696] 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[2mdocsserver # [    5.711856] systemd[1]: Finished Load Kernel Module fuse.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.577757] systemd[1]: Starting Coldplug All udev Devices...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.720196] systemd[1]: Mounting FUSE Control File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.590710] systemd[1]: Mounted Huge Pages File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.595933] systemd[1]: Mounted POSIX Message Queue File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.602300] systemd[1]: Mounted Kernel Debug File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.606982] systemd[1]: Mounted Kernel Trace File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.749898] systemd[1]: Mounted FUSE Control File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.618780] systemd[1]: Finished Create List of Static Device Nodes.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.625711] systemd[1]: modprobe@configfs.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.634754] systemd[1]: Finished Load Kernel Module configfs.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.640321] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.648032] systemd[1]: Finished Load Kernel Module efi_pstore.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.799121] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.660302] systemd[1]: Mounting Kernel Configuration File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.809092] systemd[1]: Starting Create Static Device Nodes in /dev...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.672618] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.831691] systemd[1]: Finished Load/Save OS Random Seed.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.838361] systemd[1]: Reached target First Boot Complete.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.696884] systemd[1]: Mounted Kernel Configuration File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.700406] fuse: init (API version 7.41)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.845489] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.703402] EXT4-fs (vda): re-mounted c00e5177-af61-4791-a5a3-b08482f95c0f.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.856064] systemd[1]: Finished Apply Kernel Variables.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.716786] loop: module loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.721926] systemd[1]: modprobe@fuse.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.727684] systemd-journald[389]: Collecting audit messages is enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.730658] systemd[1]: Finished Load Kernel Module fuse.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.735728] systemd[1]: Mounting FUSE Control File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.745869] systemd[1]: Finished Remount Root and Kernel File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.758690] systemd[1]: Finished Load Kernel Modules.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.772204] systemd[1]: Starting Firewall...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.776649] 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 # [    5.793604] systemd[1]: Starting Load/Save OS Random Seed...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.804658] systemd[1]: Starting Apply Kernel Variables...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.809879] 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 # [    5.954359] systemd[1]: Finished Create Static Device Nodes in /dev.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.960052] systemd[1]: Reached target Preparation for Local File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.820611] systemd[1]: Mounted FUSE Control File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.968797] systemd[1]: Starting Rule-based Manager for Device Events and Files...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.897385] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.912041] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.925107] systemd[1]: Starting Create Static Device Nodes in /dev...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.944317] systemd[1]: Finished Load/Save OS Random Seed.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.950395] systemd[1]: Reached target First Boot Complete.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.958292] systemd[1]: Finished Apply Kernel Variables.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.147247] systemd[1]: Started Journal Service.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.765589] systemd-modules-load[384]: Inserted module 'loop'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.773708] 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 # [    5.789617] systemd-udevd[411]: Using default interface naming scheme 'v258'.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.803192] systemd[1]: Starting Flush Journal to Persistent Storage...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.065715] systemd[1]: Finished Create Static Device Nodes in /dev.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.072015] systemd[1]: Reached target Preparation for Local File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.081690] systemd[1]: Starting Rule-based Manager for Device Events and Files...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.241535] systemd-journald[383]: Received client request to flush runtime journal.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.247623] systemd[1]: Started Journal Service.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.865375] systemd-modules-load[390]: Inserted module 'loop'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.874655] systemd-oomd[391]: [0;1;38:5:185mNo swap; memory pressure usage will be degraded[0m[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.893317] systemd-udevd[417]: Using default interface naming scheme 'v258'.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.907152] systemd[1]: Starting Flush Journal to Persistent Storage...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.079799] systemd[1]: Finished Coldplug All udev Devices.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.087597] systemd[1]: Started Rule-based Manager for Device Events and Files.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.095401] systemd[1]: Finished Flush Journal to Persistent Storage.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.347871] systemd-journald[389]: Received client request to flush runtime journal.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.107393] systemd[1]: Mounting /run/wrappers...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.146230] systemd[1]: Mounted /run/wrappers.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.152923] systemd[1]: Reached target Local File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.161324] systemd[1]: Listening on Boot Entries Service Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.171193] systemd[1]: Starting Create SUID/SGID Wrappers...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.176539] 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 # [    6.193947] systemd[1]: Starting Save Transient machine-id to Disk...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.201730] systemd[1]: Starting Create System Files and Directories...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.322460] systemd[1]: Finished Create System Files and Directories.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.195322] systemd[1]: Finished Coldplug All udev Devices.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.336647] systemd[1]: Starting Rebuild Journal Catalog...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.204828] systemd[1]: Started Rule-based Manager for Device Events and Files.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.350672] systemd[1]: Starting Record System Boot/Shutdown in UTMP...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.218265] systemd[1]: Finished Flush Journal to Persistent Storage.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.363211] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.225992] systemd[1]: Mounting /run/wrappers...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.372871] systemd[1]: Finished Save Transient machine-id to Disk.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.254893] systemd[1]: Mounted /run/wrappers.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.266219] systemd[1]: Reached target Local File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.277445] systemd[1]: Listening on Boot Entries Service Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.425256] systemd[1]: Finished Record System Boot/Shutdown in UTMP.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.286342] systemd[1]: Starting Create SUID/SGID Wrappers...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.296609] 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 # [    6.313437] systemd[1]: Starting Save Transient machine-id to Disk...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.323414] systemd[1]: Starting Create System Files and Directories...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.501517] systemd[1]: Finished Rebuild Journal Catalog.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.508590] systemd[1]: Starting Update is Completed...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.399219] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.413343] systemd[1]: Finished Save Transient machine-id to Disk.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.573354] systemd[1]: Finished Update is Completed.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.451350] systemd[1]: Finished Create System Files and Directories.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.463521] systemd[1]: Starting Rebuild Journal Catalog...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.474989] systemd[1]: Starting Record System Boot/Shutdown in UTMP...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.652369] systemd[1]: Found device /dev/hvc0.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.546882] systemd[1]: Finished Record System Boot/Shutdown in UTMP.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.692434] systemd[1]: Found device /dev/ttyS0.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.699457] (udev-worker)[488]: [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 # [    6.720607] (udev-worker)[488]: Network interface NamePolicy= disabled on kernel command line.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.736542] (udev-worker)[482]: Network interface NamePolicy= disabled on kernel command line.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.607368] systemd[1]: Finished Rebuild Journal Catalog.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.614543] systemd[1]: Starting Update is Completed...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.683562] systemd[1]: Finished Update is Completed.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.763267] systemd[1]: Found device /dev/hvc0.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.928856] systemd[1]: Found device Virtio network device.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.819770] systemd[1]: Found device /dev/ttyS0.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.983298] systemd[1]: Finished Firewall.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.998433] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.007405] systemd[1]: Finished Create SUID/SGID Wrappers.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.015225] systemd[1]: Reached target System Initialization.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.870696] (udev-worker)[486]: Network interface NamePolicy= disabled on kernel command line.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.021594] systemd[1]: Started Discard unused filesystem blocks once a week.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.034776] systemd[1]: Started Daily Cleanup of Temporary Directories.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.894523] (udev-worker)[499]: [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[2mclient # [    6.913288] (udev-worker)[499]: Network interface NamePolicy= disabled on kernel command line.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.056193] systemd[1]: Reached target Timer Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.068493] systemd[1]: Listening on D-Bus System Message Bus Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.079961] systemd[1]: Listening on Nix Daemon Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.089827] systemd[1]: Listening on Hostname Service Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.114547] systemd[1]: Reached target Socket Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.129445] systemd[1]: Reached target Basic System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.145602] systemd[1]: Started backdoor.service.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.161332] systemd[1]: Starting Import lastlog data into lastlog2 database...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.173740] systemd[1]: Starting Name Service Cache Daemon (nsncd)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.192281] systemd[1]: Started Reset console on configuration changes.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # connecting to host...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.208163] systemd[1]: Starting resolvconf update...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.218154] systemd[1]: Started Centjes docs site production Service.[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 8.27 seconds)
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: (finished: waiting for the VM to finish booting, in 8.27 seconds)
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.105637] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.117576] systemd[1]: Finished Create SUID/SGID Wrappers.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.233704] nsncd[623]: Apr 27 13:55:03.540 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.124717] systemd[1]: Reached target System Initialization.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.259728] systemd[1]: Starting D-Bus System Message Bus...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.275828] systemd[1]: Finished Import lastlog data into lastlog2 database.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.136863] systemd[1]: Started Discard unused filesystem blocks once a week.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.289299] systemd[1]: Started Name Service Cache Daemon (nsncd).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.296982] systemd[1]: Reached target Host and Network Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.159556] systemd[1]: Started Daily Cleanup of Temporary Directories.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.306974] dbus-daemon[627]: dbus[627]: Unknown username "systemd-timesync" in message bus configuration file[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.174612] systemd[1]: Reached target Timer Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.327981] systemd[1]: Reached target User and Group Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.187513] systemd[1]: Listening on D-Bus System Message Bus Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.335910] systemd[1]: Starting User Login Management...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.202571] systemd[1]: Listening on Nix Daemon Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.221562] systemd[1]: Listening on Hostname Service Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.233390] systemd[1]: Reached target Socket Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.375760] systemd[1]: Started D-Bus System Message Bus.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.252529] systemd[1]: Reached target Basic System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.264882] systemd[1]: Started backdoor.service.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.277380] systemd[1]: Starting Import lastlog data into lastlog2 database...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # connecting to host...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.295696] systemd[1]: Starting Name Service Cache Daemon (nsncd)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.313204] systemd[1]: Started Reset console on configuration changes.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.848296] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.469529] systemd-logind[649]: New seat seat0.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.325512] systemd[1]: Starting resolvconf update...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.339417] systemd[1]: Finished Firewall.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.872919] mousedev: PS/2 mouse device common for all mice[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.346677] systemd[1]: Found device Virtio network device.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.493457] systemd[1]: Started User Login Management.[    7.883192] ACPI: button: Power Button [PWRF][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.359580] nsncd[626]: Apr 27 13:55:03.788 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 # [    7.506876] systemd[1]: Stopped target Host and Network Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.379790] systemd[1]: Starting D-Bus System Message Bus...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.390169] systemd[1]: Finished Import lastlog data into lastlog2 database.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.527174] systemd[1]: Stopping Host and Network Name Lookups...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.399529] systemd[1]: Started Name Service Cache Daemon (nsncd).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.542843] systemd[1]: Stopped target User and Group Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.554711] systemd[1]: Stopping User and Group Name Lookups...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.411493] dbus-daemon[630]: dbus[630]: Unknown username "systemd-timesync" in message bus configuration file[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.427170] systemd[1]: Reached target Host and Network Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.565927] systemd[1]: Starting linger-users.service...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.435172] systemd[1]: Reached target User and Group Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.576690] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.588496] systemd[1]: nscd.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.442727] systemd[1]: Starting User Login Management...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.599373] systemd[1]: Stopped Name Service Cache Daemon (nsncd).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.465293] systemd[1]: Started D-Bus System Message Bus.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.615737] systemd[1]: Starting Name Service Cache Daemon (nsncd)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.628229] systemd[1]: linger-users.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.636585] systemd[1]: Finished linger-users.service.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.507378] systemd[1]: Stopped target Host and Network Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.653242] systemd[1]: Started Name Service Cache Daemon (nsncd).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.517779] systemd[1]: Stopping Host and Network Name Lookups...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.660945] nsncd[699]: Apr 27 13:55:03.974 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 # [    7.675568] systemd[1]: Reached target Host and Network Name Lookups.[    8.064152] rtc_cmos 00:05: RTC can wake from S4[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.533561] systemd[1]: Stopped target User and Group Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.681172] systemd[1]: Reached target User and Group Name Lookups.[    8.070171] parport_pc 00:03: reported by Plug and Play ACPI[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.542154] systemd[1]: Stopping User and Group Name Lookups...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.547699] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.557885] systemd[1]: nscd.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.566267] systemd[1]: Stopped Name Service Cache Daemon (nsncd).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.705865] systemd[1]: Finished resolvconf update.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.575298] systemd[1]: Starting Name Service Cache Daemon (nsncd)...[    7.964353] 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[2mdocsserver # [    7.717343] systemd[1]: Reached target Preparation for Network.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.727978] systemd[1]: Starting DHCP Client...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.584890] systemd-logind[652]: New seat seat0.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.733951] systemd[1]: Starting Address configuration of eth1...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.590676] systemd[1]: Started User Login Management.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.133441] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.599864] systemd[1]: Starting linger-users.service...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.997159] mousedev: PS/2 mouse device common for all mice[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.140707] rtc_cmos 00:05: registered as rtc0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.008653] ACPI: button: Power Button [PWRF][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.155110] Floppy drive(s): fd0 is 2.88M AMI BIOS[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.631946] systemd[1]: Started Name Service Cache Daemon (nsncd).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.639272] nsncd[690]: Apr 27 13:55:04.088 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.181131] rtc_cmos 00:05: setting system clock to 2026-04-27T13:55:03 UTC (1777298103)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.185088] systemd-journald[383]: Time jumped backwards, rotating.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.658170] systemd[1]: Reached target Host and Network Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.664562] systemd[1]: Reached target User and Group Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.670347] systemd[1]: linger-users.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.204311] 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 # [    7.675781] systemd[1]: Finished linger-users.service.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.213649] FDC 0 is a S82078B[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.703658] systemd[1]: Finished resolvconf update.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.711510] systemd[1]: Reached target Preparation for Network.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.720517] systemd[1]: Starting DHCP Client...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.726305] systemd[1]: Starting Address configuration of eth1...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.869480] network-addresses-eth1-start[730]: adding address 192.168.1.2/24... done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.888207] network-addresses-eth1-start[730]: adding address 2001:db8:1::2/64... done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.157327] rtc_cmos 00:05: RTC can wake from S4[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.916159] systemd[1]: Finished Address configuration of eth1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.923759] systemd[1]: Starting Networking Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.190367] parport_pc 00:03: reported by Plug and Play ACPI[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.193107] Floppy drive(s): fd0 is 2.88M AMI BIOS[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.207135] rtc_cmos 00:05: registered as rtc0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.211260] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.369220] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.842161] network-addresses-eth1-start[719]: adding address 192.168.1.1/24... done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.989718] dhcpcd[737]: dhcpcd-10.2.4 starting[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.246237] FDC 0 is a S82078B[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.000707] dhcpcd[752]: dev: loaded udev[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.865813] network-addresses-eth1-start[719]: adding address 2001:db8:1::1/64... done[    8.259087] rtc_cmos 00:05: setting system clock to 2026-04-27T13:55:05 UTC (1777298105)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.412350] 8021q: 802.1Q VLAN Support v1.8[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.415643] 8021q: adding VLAN 0 to HW filter on device eth1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.895432] systemd[1]: Finished Address configuration of eth1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.903427] systemd[1]: Starting Networking Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.441410] 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 # [    8.303798] 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 # [    8.457034] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.938618] dhcpcd[726]: dhcpcd-10.2.4 starting[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.335692] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.958256] dhcpcd[738]: dev: loaded udev[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.364899] 8021q: 802.1Q VLAN Support v1.8[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.368595] 8021q: adding VLAN 0 to HW filter on device eth1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.373352] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.380356] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.544513] cfg80211: Loading compiled-in X.509 certificates for regulatory database[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.550294] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.555154] cryptd: max_cpu_qlen set to 1000[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.562098] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.568171] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.204415] systemd[1]: Finished Networking Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.213469] systemd[1]: Reached target Network.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.220433] systemd[1]: Starting Permit User Sessions...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.603709] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.502658] cfg80211: Loading compiled-in X.509 certificates for regulatory database[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.603806] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.605335] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.165182] systemd[1]: Finished Networking Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.171342] systemd[1]: Reached target Network.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.177670] systemd[1]: Starting Permit User Sessions...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.605338] cfg80211: failed to load regulatory.db[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.643801] AES CTR mode by8 optimization enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.524919] Console: switching to colour dummy device 80x25[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.652158] Console: switching to colour dummy device 80x25[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.533971] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.696677] 8021q: adding VLAN 0 to HW filter on device eth0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.534071] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.310782] dhcpcd[752]: eth0: waiting for carrier[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.384692] systemd[1]: Starting Virtual Console Setup...[    8.772997] [drm] Found bochs VGA, ID 0xb0c5.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.774112] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.535619] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.535624] cfg80211: failed to load regulatory.db[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.560085] 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 # [    8.592786] cryptd: max_cpu_qlen set to 1000[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.609734] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.436236] systemd[1]: Finished Permit User Sessions.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.446240] systemd[1]: Started Getty on tty1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.610238] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.450727] systemd[1]: Reached target Login Prompts.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.637014] 8021q: adding VLAN 0 to HW filter on device eth0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.467338] systemd-logind[649]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.645681] AES CTR mode by8 optimization enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.879096] [drm] Found EDID data blob.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.501764] systemd-logind[649]: Watching system buttons on /dev/input/event2 (Power Button)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.251092] dhcpcd[738]: eth0: waiting for carrier[    8.754051] [drm] Found bochs VGA, ID 0xb0c5.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.896710] [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 # [    8.755181] [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 # [    8.371328] systemd[1]: Starting Virtual Console Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.387726] systemd-logind[652]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.422808] systemd[1]: Finished Permit User Sessions.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.435722] systemd[1]: Started Getty on tty1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.443119] systemd[1]: Reached target Login Prompts.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.858731] [drm] Found EDID data blob.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.870059] [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 # [    8.493308] systemd-logind[652]: Watching system buttons on /dev/input/event2 (Power Button)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.102438] dhcpcd[752]: eth0: carrier acquired[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.118408] dhcpcd[752]: DUID 00:01:00:01:31:82:27:38:52:54:00:12:34:56[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.121938] dhcpcd[752]: eth0: IAID 00:12:34:56[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.129140] dhcpcd[752]: eth0: adding address fe80::5054:ff:fe12:3456[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.066354] dhcpcd[738]: eth0: carrier acquired[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.078312] dhcpcd[738]: DUID 00:01:00:01:31:82:27:3a:52:54:00:12:34:56[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.080549] dhcpcd[738]: eth0: IAID 00:12:34:56[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.082471] dhcpcd[738]: eth0: adding address fe80::5054:ff:fe12:3456[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.925110] fbcon: bochs-drmdrmfb (fb0) is primary device[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.546716] Console: switching to colour frame buffer device 160x50[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.684333] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.907075] fbcon: bochs-drmdrmfb (fb0) is primary device[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.475286] ppdev: user-space parallel port driver[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.489481] Console: switching to colour frame buffer device 160x50[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.625032] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.877163] ppdev: user-space parallel port driver[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.531350] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.933082] kvm_amd: TSC scaling supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.934205] kvm_amd: Nested Virtualization enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.935384] kvm_amd: Nested Paging enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.936395] kvm_amd: LBR virtualization supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.413831] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.821678] kvm_amd: TSC scaling supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.822720] kvm_amd: Nested Virtualization enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.823853] kvm_amd: Nested Paging enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.824900] kvm_amd: LBR virtualization supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.979835] kvm_amd: Virtual VMLOAD VMSAVE supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.981188] kvm_amd: Virtual GIF supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.606813] systemd-logind[649]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.872487] kvm_amd: Virtual VMLOAD VMSAVE supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.873825] kvm_amd: Virtual GIF supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.513780] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.520972] systemd[1]: Stopped Virtual Console Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.662599] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.526913] systemd-logind[652]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.674532] systemd[1]: Stopped Virtual Console Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.536575] systemd[1]: Starting Virtual Console Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.679593] systemd[1]: Starting Virtual Console Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.703807] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.714620] systemd[1]: Stopped Virtual Console Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.717380] systemd[1]: Starting Virtual Console Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.120302] EDAC MC: Ver: 3.0.0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.601433] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.612415] systemd[1]: Stopped Virtual Console Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.003002] EDAC MC: Ver: 3.0.0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.618417] systemd[1]: Starting Virtual Console Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.126164] systemd[1]: Finished Virtual Console Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.019375] systemd[1]: Finished Virtual Console Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.104793] dhcpcd[738]: eth0: soliciting a DHCP lease[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.509918] NET: Registered PF_PACKET protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.131724] dhcpcd[738]: eth0: offered 10.0.2.15 from 10.0.2.2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.135338] dhcpcd[738]: eth0: probing address 10.0.2.15/24[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.324780] dhcpcd[752]: eth0: soliciting a DHCP lease[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.732030] NET: Registered PF_PACKET protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.354713] dhcpcd[752]: eth0: offered 10.0.2.15 from 10.0.2.2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.358351] dhcpcd[752]: eth0: probing address 10.0.2.15/24[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.720099] dhcpcd[752]: eth0: soliciting an IPv6 router[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.722545] dhcpcd[752]: eth0: Router Advertisement from fe80::2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.724523] dhcpcd[752]: eth0: adding address fec0::5054:ff:fe12:3456/64[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.726751] dhcpcd[752]: eth0: adding route to fec0::/64[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.728653] dhcpcd[752]: eth0: adding default route via fe80::2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.153247] dhcpcd[738]: eth0: soliciting an IPv6 router[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.155306] dhcpcd[738]: eth0: Router Advertisement from fe80::2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.157360] dhcpcd[738]: eth0: adding address fec0::5054:ff:fe12:3456/64[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.159463] dhcpcd[738]: eth0: adding route to fec0::/64[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.161439] dhcpcd[738]: eth0: adding default route via fe80::2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   15.080469] dhcpcd[752]: eth0: leased 10.0.2.15 for 86400 seconds[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   15.082812] dhcpcd[752]: eth0: adding route to 10.0.2.0/24[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   15.084875] dhcpcd[752]: eth0: adding default route via 10.0.2.2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   15.193586] systemd[1]: Started DHCP Client.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   15.198455] systemd[1]: Reached target Multi-User System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   15.200328] systemd[1]: Startup finished in 3.440s (kernel) + 11.756s (userspace) = 15.196s.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   15.627633] dhcpcd[738]: eth0: leased 10.0.2.15 for 86400 seconds[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   15.629894] dhcpcd[738]: eth0: adding route to 10.0.2.0/24[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   15.632095] dhcpcd[738]: eth0: adding default route via 10.0.2.2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   15.743305] systemd[1]: Started DHCP Client.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   15.746701] systemd[1]: Reached target Multi-User System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   15.749868] systemd[1]: Startup finished in 3.472s (kernel) + 12.275s (userspace) = 15.748s.[0m
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: (finished: waiting for unit default.target, in 17.03 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.07 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 # [   16.503521] centjes-docs-site-production-start[635]: 192.168.1.1 - - [27/Apr/2026:13:55:12 +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 61240     0  --:--:-- --:--:-- --:--:-- 63070[0m
[2m[vm-test-run-centjes-e2e-test] [mclient: (finished: must succeed: curl docsserver:8001, in 0.15 seconds)
[2m[vm-test-run-centjes-e2e-test] [m(finished: run the VM test script, in 17.81 seconds)
[2m[vm-test-run-centjes-e2e-test] [mtest script finished in 17.95s
[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.03 seconds)
[2m[post-build-hook] [m[2mUploading paths from nix-ci to cachix cache "centjes" /nix/store/1611s7j372lwqw5b36c5v56df4ya6bca-vm-test-run-centjes-e2e-test[m
[2m[post-build-hook] [m[2mPushing 1 paths (0 are already present) using zstd to cache centjes ⏳[m
[2m[post-build-hook] [m[2m[m
[2m[post-build-hook] [m[2mPushing /nix/store/1611s7j372lwqw5b36c5v56df4ya6bca-vm-test-run-centjes-e2e-test (96.00 B)[m
[2m[post-build-hook] [m[2m[m
[2m[post-build-hook] [m[2mAll done.[m
[2m[post-build-hook] [m[2mUploading paths from nix-ci to the NixCI staging cache /nix/store/1611s7j372lwqw5b36c5v56df4ya6bca-vm-test-run-centjes-e2e-test[m
[2m[post-build-hook] [m[2mwarning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead[m
[2m[post-build-hook] [m[2mcopying 1 paths...[m
[2m[post-build-hook] [m[2mcopying path '/nix/store/1611s7j372lwqw5b36c5v56df4ya6bca-vm-test-run-centjes-e2e-test' to 'https://cache.staging.nix-ci.com'...[m
[2m[post-build-hook] [m[2mwarning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead[m
[2m[post-build-hook] [m[2mcopying 1 paths...[m
[2m[post-build-hook] [m[2mcopying path '/nix/store/5l7rsrwmqp06f0b3n39irva97zdcvawh-vm-test-run-centjes-e2e-test.drv' to 'https://cache.staging.nix-ci.com'...[m
[2mProgress: 7 of 8 built[m