$ /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=a5ac38ca38a8ee4de9eabeebe725de50cf47e5f3#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
[32mSubstituting unit-script-centjes-docs-site-production-start from https://cache.staging.nix-ci.com[m
[2mProgress: 0 of 2 built, 1 of 9 fetched[m
[32mSubstituting settings-check from https://cache.staging.nix-ci.com[m
[2mProgress: 0 of 11 built, 2 of 2 fetched[m
[1;34mBuilding settings-check[m
[2m[settings-check] [m[34mWithConfig[m: [36msrc/Centjes/Docs/Site/OptParse.hs:27:7 in centjes-docs-site:Centjes.Docs.Site.OptParse[m
[2m[settings-check] [m  loading config
[2m[settings-check] [m    [34mParser with check[m: [36msrc/Centjes/Docs/Site/OptParse.hs:27:7 in centjes-docs-site:Centjes.Docs.Site.OptParse[m
[2m[settings-check] [m      parser
[2m[settings-check] [m        [34mAlt[m
[2m[settings-check] [m          Trying left side.
[2m[settings-check] [m            [34mParser with check[m: [36msrc/Centjes/Docs/Site/OptParse.hs:27:7 in centjes-docs-site:Centjes.Docs.Site.OptParse[m
[2m[settings-check] [m              parser
[2m[settings-check] [m                [34mSetting[m: [36msrc/Centjes/Docs/Site/OptParse.hs:27:7 in centjes-docs-site:Centjes.Docs.Site.OptParse[m
[2m[settings-check] [m                  could not set based on options, no option: ["--config-file"]
[2m[settings-check] [m                  set based on env: "/nix/store/vb9vv8cg7xwfa7010fyznaj7b5syrx63-centjes-docs-site-config.yaml"
[2m[settings-check] [m              check
[2m[settings-check] [m                succeeded
[2m[settings-check] [m          Left side succeeded.
[2m[settings-check] [m      check
[2m[settings-check] [m        succeeded
[2m[settings-check] [m  with loaded config
[2m[settings-check] [m    [34mAp[m
[2m[settings-check] [m      [34mAp[m
[2m[settings-check] [m        [34mParser with check[m: without srcLoc
[2m[settings-check] [m          parser
[2m[settings-check] [m            [34mSetting[m: [36msrc/Centjes/Docs/Site/OptParse.hs:29:11 in centjes-docs-site:Centjes.Docs.Site.OptParse[m
[2m[settings-check] [m              could not set based on options, no option: ["--port"]
[2m[settings-check] [m              could not set based on env vars, no var: [EnvVarSetting {envVarSettingVar = "CENTJES_DOCS_SITE_PORT", envVarSettingAllowPrefix = True}]
[2m[settings-check] [m              set based on config value: Number 8001.0
[2m[settings-check] [m          check
[2m[settings-check] [m            succeeded
[2m[settings-check] [m        [34mAlt[m
[2m[settings-check] [m          Trying left side.
[2m[settings-check] [m            [34mParser with check[m: without srcLoc
[2m[settings-check] [m              parser
[2m[settings-check] [m                [34mSetting[m: [36msrc/Centjes/Docs/Site/OptParse.hs:38:13 in centjes-docs-site:Centjes.Docs.Site.OptParse[m
[2m[settings-check] [m                  could not set based on options, no option: ["--google-analytics-tracking"]
[2m[settings-check] [m                  could not set based on env vars, no var: [EnvVarSetting {envVarSettingVar = "CENTJES_DOCS_SITE_GOOGLE_ANALYTICS_TRACKING", envVarSettingAllowPrefix = True}]
[2m[settings-check] [m                  could not set based on config value, configured to nothing: ["google-analytics-tracking"]
[2m[settings-check] [m                  not found
[2m[settings-check] [m              check
[2m[settings-check] [m          Left side failed, trying right side.
[2m[settings-check] [m            [34mpure value[m
[2m[settings-check] [m      [34mAlt[m
[2m[settings-check] [m        Trying left side.
[2m[settings-check] [m          [34mParser with check[m: without srcLoc
[2m[settings-check] [m            parser
[2m[settings-check] [m              [34mSetting[m: [36msrc/Centjes/Docs/Site/OptParse.hs:46:13 in centjes-docs-site:Centjes.Docs.Site.OptParse[m
[2m[settings-check] [m                could not set based on options, no option: ["--google-search-console-verification"]
[2m[settings-check] [m                could not set based on env vars, no var: [EnvVarSetting {envVarSettingVar = "CENTJES_DOCS_SITE_GOOGLE_SEARCH_CONSOLE_VERIFICATION", envVarSettingAllowPrefix = True}]
[2m[settings-check] [m                could not set based on config value, configured to nothing: ["google-search-console-verification"]
[2m[settings-check] [m                not found
[2m[settings-check] [m            check
[2m[settings-check] [m        Left side failed, trying right side.
[2m[settings-check] [m          [34mpure value[m
[2m[post-build-hook] [m[2mUploading paths from nix-ci to cachix cache "centjes" /nix/store/pfg8hayvf5qq1axqbh3zkfrk33031syf-settings-check[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/pfg8hayvf5qq1axqbh3zkfrk33031syf-settings-check (144.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/pfg8hayvf5qq1axqbh3zkfrk33031syf-settings-check[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/pfg8hayvf5qq1axqbh3zkfrk33031syf-settings-check' 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 0 paths...[m
[2mProgress: 1 of 11 built, 2 of 2 fetched[m
[1;34mBuilding unit-centjes-docs-site-production.service[m
[2m[post-build-hook] [m[2mUploading paths from nix-ci to cachix cache "centjes" /nix/store/6hbdzqdw69aadrd9hh7a372dxwdl6cxf-unit-centjes-docs-site-production.service[m
[2m[post-build-hook] [m[2mPushing 2 paths (90 are already present) using zstd to cache centjes ⏳[m
[2m[post-build-hook] [m[2m[m
[2m[post-build-hook] [m[2mPushing /nix/store/6hbdzqdw69aadrd9hh7a372dxwdl6cxf-unit-centjes-docs-site-production.service (1.62 KiB)[m
[2m[post-build-hook] [m[2mPushing /nix/store/f1crdx2m27wb32drgd0j57ad53pjpn1j-unit-script-centjes-docs-site-production-start (680.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/6hbdzqdw69aadrd9hh7a372dxwdl6cxf-unit-centjes-docs-site-production.service[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 0 paths...[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 0 paths...[m
[2mProgress: 2 of 11 built, 2 of 2 fetched[m
[1;34mBuilding system-units[m
[2m[post-build-hook] [m[2mUploading paths from nix-ci to cachix cache "centjes" /nix/store/jnirg3gqxy9g8l8gagj1cwq9r385hcjs-system-units[m
[2m[post-build-hook] [m[2mPushing 1 paths (450 are already present) using zstd to cache centjes ⏳[m
[2m[post-build-hook] [m[2m[m
[2m[post-build-hook] [m[2mPushing /nix/store/jnirg3gqxy9g8l8gagj1cwq9r385hcjs-system-units (96.09 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/jnirg3gqxy9g8l8gagj1cwq9r385hcjs-system-units[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 0 paths...[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 0 paths...[m
[2mProgress: 3 of 11 built, 2 of 2 fetched[m
[1;34mBuilding etc[m
[2m[post-build-hook] [m[2mUploading paths from nix-ci to cachix cache "centjes" /nix/store/dj7drq2wfn99iwkdhq5qgc6gd05ik7g2-etc[m
[2m[post-build-hook] [m[2mPushing 1 paths (546 are already present) using zstd to cache centjes ⏳[m
[2m[post-build-hook] [m[2m[m
[2m[post-build-hook] [m[2mPushing /nix/store/dj7drq2wfn99iwkdhq5qgc6gd05ik7g2-etc (31.56 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/dj7drq2wfn99iwkdhq5qgc6gd05ik7g2-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 0 paths...[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 0 paths...[m
[2mProgress: 4 of 11 built, 2 of 2 fetched[m
[1;34mBuilding nixos-system-docsserver-test[m
[2m[post-build-hook] [m[2mUploading paths from nix-ci to cachix cache "centjes" /nix/store/aycj65q2vjaiwwbk57wba4kaxs7226vn-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/aycj65q2vjaiwwbk57wba4kaxs7226vn-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/aycj65q2vjaiwwbk57wba4kaxs7226vn-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 0 paths...[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 0 paths...[m
[2mProgress: 5 of 11 built, 2 of 2 fetched[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/c7vwm5q2a9xvn7hzifddkzzbr1s7yinr-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/c7vwm5q2a9xvn7hzifddkzzbr1s7yinr-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/c7vwm5q2a9xvn7hzifddkzzbr1s7yinr-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 0 paths...[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 0 paths...[m
[2mProgress: 6 of 11 built, 2 of 2 fetched[m
[1;34mBuilding run-nixos-vm[m
[2m[post-build-hook] [m[2mUploading paths from nix-ci to cachix cache "centjes" /nix/store/mkd791xnc7ji087bbydjm2ps1al6dlfj-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/mkd791xnc7ji087bbydjm2ps1al6dlfj-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/mkd791xnc7ji087bbydjm2ps1al6dlfj-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 0 paths...[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 0 paths...[m
[2mProgress: 7 of 11 built, 2 of 2 fetched[m
[1;34mBuilding nixos-vm[m
[2m[post-build-hook] [m[2mUploading paths from nix-ci to cachix cache "centjes" /nix/store/l6ghf1y7jb40r81ixn986qvrj01y1nyx-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/l6ghf1y7jb40r81ixn986qvrj01y1nyx-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/l6ghf1y7jb40r81ixn986qvrj01y1nyx-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 0 paths...[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 0 paths...[m
[2mProgress: 8 of 11 built, 2 of 2 fetched[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/0ac838496b06q422qk2nvdhbixqlkib7-nixos-test-driver-centjes-e2e-test[m
[2m[post-build-hook] [m[2mPushing 2 paths (632 are already present) using zstd to cache centjes ⏳[m
[2m[post-build-hook] [m[2m[m
[2m[post-build-hook] [m[2mPushing /nix/store/0ac838496b06q422qk2nvdhbixqlkib7-nixos-test-driver-centjes-e2e-test (1.84 KiB)[m
[2m[post-build-hook] [m[2mPushing /nix/store/rwj0jbi98wrrg4c4k8a5s63cp8r7s8a9-which-2.23 (46.29 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/0ac838496b06q422qk2nvdhbixqlkib7-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 0 paths...[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 0 paths...[m
[2mProgress: 9 of 11 built, 2 of 2 fetched[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.XpVzYk7Gcb', fmt=raw size=1073741824[0m
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: QEMU running (pid 9)
[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: 069dba71-c190-4124-a059-4338a1e8327d[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Superblock backups stored on blocks:[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # 	32768, 98304, 163840, 229376[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Allocating group tables: 0/8   done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Writing inode tables: 0/8   done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Creating journal (8192 blocks): done[0m
[2m[vm-test-run-centjes-e2e-test] [mclient: starting vm
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Writing superblocks and filesystem accounting information: 0/8   done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Virtualisation disk image created.[0m
[2m[vm-test-run-centjes-e2e-test] [mmke2fs 1.47.3 (8-Jul-2025)
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # c[?7l[2J[0mSeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)[0m
[2m[vm-test-run-centjes-e2e-test] [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.QAiUugYhmP', 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: 2bf68771-21da-49aa-8efa-0256cf5823b1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Superblock backups stored on blocks:[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # 	32768, 98304, 163840, 229376[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Allocating group tables: 0/8   done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Writing inode tables: 0/8   done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Creating journal (8192 blocks): done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Writing superblocks and filesystem accounting information: 0/8   done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # Virtualisation disk image created.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+3EFD1920+3EF31920 CA00[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Press Ctrl-B to configure iPXE (PCI 00:03.0)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM 3EFD1920 3EF31920 CB00[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Press Ctrl-B to configure iPXE (PCI 00:09.0)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Booting from ROM...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # Probing EDD (edd=off to disable)... ok[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[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/aycj65q2vjaiwwbk57wba4kaxs7226vn-nixos-system-docsserver-test/init regInfo=/nix/store/c7vwm5q2a9xvn7hzifddkzzbr1s7yinr-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.000001] kvm-clock: using sched offset of 510857512 cycles[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000005] tsc: Detected 3399.996 MHz processor[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.000816] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.001067] 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.001076] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003144] found SMP MP-table at [mem 0x000f5470-0x000f547f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003157] Using GB pages for direct mapping[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003301] RAMDISK: [mem 0x3f3cd000-0x3ffcffff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003330] ACPI: Early table checksum verification disabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003333] ACPI: RSDP 0x00000000000F5290 000014 (v00 BOCHS )[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003337] ACPI: RSDT 0x000000003FFE23D9 000034 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003342] ACPI: FACP 0x000000003FFE228D 000074 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003346] ACPI: DSDT 0x000000003FFE0040 00224D (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003348] ACPI: FACS 0x000000003FFE0000 000040[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003350] ACPI: APIC 0x000000003FFE2301 000078 (v03 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003352] ACPI: HPET 0x000000003FFE2379 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003353] ACPI: WAET 0x000000003FFE23B1 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003354] ACPI: Reserving FACP table memory at [mem 0x3ffe228d-0x3ffe2300][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003356] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe228c][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003356] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003357] ACPI: Reserving APIC table memory at [mem 0x3ffe2301-0x3ffe2378][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003357] ACPI: Reserving HPET table memory at [mem 0x3ffe2379-0x3ffe23b0][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.003358] ACPI: Reserving WAET table memory at [mem 0x3ffe23b1-0x3ffe23d8][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.004242] No NUMA configuration found[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.004243] Faking a node at [mem 0x0000000000000000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.004247] NODE_DATA(0) allocated [mem 0x3ffd58c0-0x3ffdadff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.004297] Zone ranges:[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.004298]   DMA      [mem 0x0000000000001000-0x0000000000ffffff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.004300]   DMA32    [mem 0x0000000001000000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.004301]   Normal   empty[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.004302]   Device   empty[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.004303] Movable zone start for each node[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.004304] Early memory node ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.004304]   node   0: [mem 0x0000000000001000-0x000000000009efff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.004305]   node   0: [mem 0x0000000000100000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.004307] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.004528] On node 0, zone DMA: 1 pages in unavailable ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.004542] On node 0, zone DMA: 97 pages in unavailable ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.006705] On node 0, zone DMA32: 37 pages in unavailable ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.008818] ACPI: PM-Timer IO Port: 0x608[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.008857] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.008969] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.008972] 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.008974] 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.008975] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.008975] 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.008976] 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.008979] ACPI: Using ACPI (MADT) for SMP configuration information[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.008979] ACPI: HPET id: 0x8086a201 base: 0xfed00000[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.008983] TSC deadline timer available[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.008987] CPU topo: Max. logical packages:   1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.008988] CPU topo: Max. logical dies:       1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.008988] CPU topo: Max. dies per package:   1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.008992] CPU topo: Max. threads per core:   1[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.008992] CPU topo: Num. cores per package:     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.008992] CPU topo: Num. threads per package:   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.008993] 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 0x000000000009fc00-0x000000000009ffff] reserved[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.009027] 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 0x00000000000f0000-0x00000000000fffff] reserved[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.009090] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffdafff] usable[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000000] BIOS-e820: [mem 0x000000003ffdb000-0x000000003fffffff] reserved[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.009092] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff][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.009093] [mem 0x40000000-0xfeffbfff] available for PCI devices[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.009094] Booting paravirtualized kernel on KVM[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[2mclient # [    0.000000] NX (Execute Disable) protection: active[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.009096] 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] 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.013014] 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.013415] percpu: Embedded 116 pages/cpu s294912 r65536 d114688 u2097152[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.013503] kvm-guest: PV spinlocks disabled, single CPU[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[2mclient # [    0.000000] Hypervisor detected: KVM[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.000001] kvm-clock: using sched offset of 517859417 cycles[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.013505] 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/aycj65q2vjaiwwbk57wba4kaxs7226vn-nixos-system-docsserver-test/init regInfo=/nix/store/c7vwm5q2a9xvn7hzifddkzzbr1s7yinr-closure-info/registration console=ttyS0,115200n8 console=tty0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000005] tsc: Detected 3399.996 MHz processor[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.013587] Unknown kernel command line parameters "regInfo=/nix/store/c7vwm5q2a9xvn7hzifddkzzbr1s7yinr-closure-info/registration", will be passed to user space.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.013601] random: crng init done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000746] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.013664] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000990] 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.013698] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.013740] Fallback order for Node 0: 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.000999] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.013742] Built 1 zonelists, mobility grouping on.  Total pages: 262009[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002980] found SMP MP-table at [mem 0x000f5470-0x000f547f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.013743] Policy zone: DMA32[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.002992] Using GB pages for direct mapping[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.014055] mem auto-init: stack:all(zero), heap alloc:on, heap free:off[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003062] RAMDISK: [mem 0x3f3cd000-0x3ffcffff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003083] ACPI: Early table checksum verification disabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.016749] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.017081] allocated 2097152 bytes of page_ext[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003085] ACPI: RSDP 0x00000000000F5290 000014 (v00 BOCHS )[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.017097] ftrace: allocating 46208 entries in 181 pages[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.025657] ftrace: allocated 181 pages with 5 groups[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003088] ACPI: RSDT 0x000000003FFE23D9 000034 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.026342] Dynamic Preempt: voluntary[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.026612] rcu: Preemptible hierarchical RCU implementation.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003093] ACPI: FACP 0x000000003FFE228D 000074 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.026613] rcu: 	RCU event tracing is enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.026614] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003096] ACPI: DSDT 0x000000003FFE0040 00224D (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003098] ACPI: FACS 0x000000003FFE0000 000040[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.026615] 	Trampoline variant of Tasks RCU enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.026615] 	Rude variant of Tasks RCU enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003099] ACPI: APIC 0x000000003FFE2301 000078 (v03 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.026616] 	Tracing variant of Tasks RCU enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003101] ACPI: HPET 0x000000003FFE2379 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.026616] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.026617] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003103] ACPI: WAET 0x000000003FFE23B1 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003104] ACPI: Reserving FACP table memory at [mem 0x3ffe228d-0x3ffe2300][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.026624] 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.003105] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe228c][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.026626] 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.003105] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.026627] 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.003106] ACPI: Reserving APIC table memory at [mem 0x3ffe2301-0x3ffe2378][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.030889] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003106] ACPI: Reserving HPET table memory at [mem 0x3ffe2379-0x3ffe23b0][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.031182] rcu: srcu_init: Setting srcu_struct sizes based on contention.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003107] ACPI: Reserving WAET table memory at [mem 0x3ffe23b1-0x3ffe23d8][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003961] No NUMA configuration found[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.031303] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003962] Faking a node at [mem 0x0000000000000000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.048037] Console: colour VGA+ 80x25[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.048043] printk: legacy console [tty0] enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003965] NODE_DATA(0) allocated [mem 0x3ffd58c0-0x3ffdadff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003981] Zone ranges:[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.172891] printk: legacy console [ttyS0] enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003981]   DMA      [mem 0x0000000000001000-0x0000000000ffffff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.518482] ACPI: Core revision 20240827[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003982]   DMA32    [mem 0x0000000001000000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003983]   Normal   empty[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003984]   Device   empty[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.522046] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003985] Movable zone start for each node[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.528182] APIC: Switch to symmetric I/O mode setup[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003985] Early memory node ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003985]   node   0: [mem 0x0000000000001000-0x000000000009efff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.532423] x2apic enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003986]   node   0: [mem 0x0000000000100000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.535788] APIC: Switched APIC routing to: physical x2apic[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.003987] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.004190] On node 0, zone DMA: 1 pages in unavailable ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.004203] On node 0, zone DMA: 97 pages in unavailable ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.544744] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.006197] On node 0, zone DMA32: 37 pages in unavailable ranges[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.007995] ACPI: PM-Timer IO Port: 0x608[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.548815] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008022] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008116] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.555553] Calibrating delay loop (skipped) preset value.. 6799.99 BogoMIPS (lpj=3399996)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008118] 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.558362] x86/cpu: User Mode Instruction Prevention (UMIP) activated[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008120] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008120] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.560160] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008121] 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.561551] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008122] 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.563565] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008124] ACPI: Using ACPI (MADT) for SMP configuration information[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.565553] Spectre V2 : Mitigation: Retpolines[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008125] ACPI: HPET id: 0x8086a201 base: 0xfed00000[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008127] TSC deadline timer available[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.567551] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008131] CPU topo: Max. logical packages:   1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008132] CPU topo: Max. logical dies:       1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.569551] Spectre V2 : Enabling Restricted Speculation for firmware calls[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008132] CPU topo: Max. dies per package:   1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008135] CPU topo: Max. threads per core:   1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.571555] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008136] CPU topo: Num. cores per package:     1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008136] CPU topo: Num. threads per package:   1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.573552] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008137] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.575552] active return thunk: srso_alias_return_thunk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008167] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.577551] Speculative Return Stack Overflow: Mitigation: Safe RET[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008226] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.579551] Transient Scheduler Attacks: Forcing mitigation on in a VM[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008228] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.581551] Transient Scheduler Attacks: Vulnerable: Clear CPU buffers attempted, no microcode[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008229] [mem 0x40000000-0xfeffbfff] available for PCI devices[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.583648] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008230] Booting paravirtualized kernel on KVM[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.008232] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.011912] 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.012211] percpu: Embedded 116 pages/cpu s294912 r65536 d114688 u2097152[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.012289] kvm-guest: PV spinlocks disabled, single CPU[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.585551] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.587553] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.589552] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.591552] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.012290] 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.593551] x86/fpu: xstate_offset[9]:  832, xstate_sizes[9]:    8[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.012369] 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.012383] random: crng init done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.595553] 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.012440] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.012469] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.012507] Fallback order for Node 0: 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.012510] Built 1 zonelists, mobility grouping on.  Total pages: 262009[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.012510] Policy zone: DMA32[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.012784] mem auto-init: stack:all(zero), heap alloc:on, heap free:off[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.014984] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.015266] allocated 2097152 bytes of page_ext[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.015279] ftrace: allocating 46208 entries in 181 pages[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.023450] ftrace: allocated 181 pages with 5 groups[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.024116] Dynamic Preempt: voluntary[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.024379] rcu: Preemptible hierarchical RCU implementation.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.629903] Freeing SMP alternatives memory: 40K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.024380] rcu: 	RCU event tracing is enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.632552] pid_max: default: 32768 minimum: 301[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.024381] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.633616] LSM: initializing lsm=capability,landlock,yama,bpf[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.024382] 	Trampoline variant of Tasks RCU enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.636629] landlock: Up and running.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.024382] 	Rude variant of Tasks RCU enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.637552] Yama: becoming mindful.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.024382] 	Tracing variant of Tasks RCU enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.639814] LSM support for eBPF active[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.024383] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.641595] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.024384] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.024392] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.643554] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.024394] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.646103] 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.024395] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.647830] Performance Events: Fam17h+ core perfctr, AMD PMU driver.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.028350] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.649572] ... version:                0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.028629] rcu: srcu_init: Setting srcu_struct sizes based on contention.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.650563] ... bit width:              48[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.028706] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.651563] ... generic registers:      6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.044365] Console: colour VGA+ 80x25[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.044370] printk: legacy console [tty0] enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.652563] ... value mask:             0000ffffffffffff[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.173277] printk: legacy console [ttyS0] enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.556319] ACPI: Core revision 20240827[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.654562] ... max period:             00007fffffffffff[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.655561] ... fixed-purpose events:   0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.559009] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.656561] ... event mask:             000000000000003f[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.657659] signal: max sigframe size: 3376[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.564173] APIC: Switch to symmetric I/O mode setup[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.658656] rcu: Hierarchical SRCU implementation.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.568577] x2apic enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.659561] rcu: 	Max phase no-delay instances is 400.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.571547] APIC: Switched APIC routing to: physical x2apic[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.665112] smp: Bringing up secondary CPUs ...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.666580] smp: Brought up 1 node, 1 CPU[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.580426] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.668564] smpboot: Total of 1 processors activated (6799.99 BogoMIPS)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.584147] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.590913] Calibrating delay loop (skipped) preset value.. 6799.99 BogoMIPS (lpj=3399996)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.670999] Memory: 962120K/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.672763] devtmpfs: initialized[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.594612] x86/cpu: User Mode Instruction Prevention (UMIP) activated[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.673639] x86/mm: Memory block size: 128MB[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.598427] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.675140] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.599911] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.676568] futex hash table entries: 256 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.678647] pinctrl core: initialized pinctrl subsystem[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.601926] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.603915] Spectre V2 : Mitigation: Retpolines[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.680998] PM: RTC time: 17:49:40, date: 2026-04-27[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.683388] NET: Registered PF_NETLINK/PF_ROUTE protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.605912] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.684803] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.607912] Spectre V2 : Enabling Restricted Speculation for firmware calls[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.686575] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.609916] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.688573] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.611913] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.690579] audit: initializing netlink subsys (disabled)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.613912] active return thunk: srso_alias_return_thunk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.692630] audit: type=2000 audit(1777312180.119:1): state=initialized audit_enabled=0 res=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.615911] Speculative Return Stack Overflow: Mitigation: Safe RET[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.617911] Transient Scheduler Attacks: Forcing mitigation on in a VM[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.694720] thermal_sys: Registered thermal governor 'bang_bang'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.694723] thermal_sys: Registered thermal governor 'step_wise'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.619912] Transient Scheduler Attacks: Vulnerable: Clear CPU buffers attempted, no microcode[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.696572] thermal_sys: Registered thermal governor 'user_space'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.622027] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.698588] cpuidle: using governor menu[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.703976] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.705880] PCI: Using configuration type 1 for base access[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.707577] PCI: Using configuration type 1 for extended access[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.623912] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.625914] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.708830] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.627912] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.629913] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.631912] x86/fpu: xstate_offset[9]:  832, xstate_sizes[9]:    8[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.633912] 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.733683] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.735566] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.737565] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.739568] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.664372] Freeing SMP alternatives memory: 40K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.665914] pid_max: default: 32768 minimum: 301[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.749820] ACPI: Added _OSI(Module Device)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.667969] LSM: initializing lsm=capability,landlock,yama,bpf[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.751562] ACPI: Added _OSI(Processor Device)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.670011] landlock: Up and running.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.670913] Yama: becoming mindful.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.753565] ACPI: Added _OSI(Processor Aggregator Device)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.674170] LSM support for eBPF active[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.676929] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.758210] ACPI: 1 ACPI AML tables successfully acquired and loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.678915] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.761929] ACPI: Interpreter enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.763586] ACPI: PM: (supports S0 S3 S4 S5)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.681470] 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.765565] ACPI: Using IOAPIC for interrupt routing[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.683268] Performance Events: Fam17h+ core perfctr, AMD PMU driver.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.684929] ... version:                0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.685921] ... bit width:              48[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.767614] 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.686921] ... generic registers:      6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.769566] PCI: Using E820 reservations for host bridge windows[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.687923] ... value mask:             0000ffffffffffff[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.689923] ... max period:             00007fffffffffff[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.771872] ACPI: Enabled 2 GPEs in block 00 to 0F[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.690921] ... fixed-purpose events:   0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.691920] ... event mask:             000000000000003f[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.693048] signal: max sigframe size: 3376[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.779411] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.695004] rcu: Hierarchical SRCU implementation.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.696921] rcu: 	Max phase no-delay instances is 400.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.780573] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.783085] acpiphp: Slot [3] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.701947] smp: Bringing up secondary CPUs ...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.703937] smp: Brought up 1 node, 1 CPU[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.784656] acpiphp: Slot [4] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.786628] acpiphp: Slot [5] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.704927] smpboot: Total of 1 processors activated (6799.99 BogoMIPS)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.788628] acpiphp: Slot [6] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.790623] acpiphp: Slot [7] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.707297] Memory: 962112K/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.792622] acpiphp: Slot [8] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.709090] devtmpfs: initialized[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.794627] acpiphp: Slot [9] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.709998] x86/mm: Memory block size: 128MB[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.796623] acpiphp: Slot [10] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.798643] acpiphp: Slot [11] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.711500] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.800624] acpiphp: Slot [12] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.712925] futex hash table entries: 256 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.802628] acpiphp: Slot [13] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.714998] pinctrl core: initialized pinctrl subsystem[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.804620] acpiphp: Slot [14] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.716286] PM: RTC time: 17:49:40, date: 2026-04-27[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.806624] acpiphp: Slot [15] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.808633] acpiphp: Slot [16] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.718804] NET: Registered PF_NETLINK/PF_ROUTE protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.810622] acpiphp: Slot [17] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.720139] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.812625] acpiphp: Slot [18] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.814624] acpiphp: Slot [19] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.721941] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.816628] acpiphp: Slot [20] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.723934] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.818622] acpiphp: Slot [21] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.725937] audit: initializing netlink subsys (disabled)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.820622] acpiphp: Slot [22] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.822621] acpiphp: Slot [23] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.726981] audit: type=2000 audit(1777312180.309:1): state=initialized audit_enabled=0 res=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.824625] acpiphp: Slot [24] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.729078] thermal_sys: Registered thermal governor 'bang_bang'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.826623] acpiphp: Slot [25] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.729081] thermal_sys: Registered thermal governor 'step_wise'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.828650] acpiphp: Slot [26] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.730924] thermal_sys: Registered thermal governor 'user_space'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.830627] acpiphp: Slot [27] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.731935] cpuidle: using governor menu[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.832628] acpiphp: Slot [28] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.737215] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.834622] acpiphp: Slot [29] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.739268] PCI: Using configuration type 1 for base access[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.836625] acpiphp: Slot [30] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.740924] PCI: Using configuration type 1 for extended access[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.838629] acpiphp: Slot [31] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.840632] PCI host bridge to bus 0000:00[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.743092] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.842576] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.844565] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.846565] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.848566] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.850566] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.770156] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.852569] pci_bus 0000:00: root bus resource [bus 00-ff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.771922] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.773924] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.855355] 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.775921] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.858447] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.784776] ACPI: Added _OSI(Module Device)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.785921] ACPI: Added _OSI(Processor Device)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.862194] 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.786924] ACPI: Added _OSI(Processor Aggregator Device)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.790826] ACPI: 1 ACPI AML tables successfully acquired and loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.793178] ACPI: Interpreter enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.869339] pci 0000:00:01.1: BAR 4 [io  0xc1e0-0xc1ef][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.794947] ACPI: PM: (supports S0 S3 S4 S5)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.795922] ACPI: Using IOAPIC for interrupt routing[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.872666] pci 0000:00:01.1: BAR 0 [io  0x01f0-0x01f7]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.796947] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.874565] pci 0000:00:01.1: BAR 1 [io  0x03f6]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.798922] PCI: Using E820 reservations for host bridge windows[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.800149] ACPI: Enabled 2 GPEs in block 00 to 0F[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.876565] pci 0000:00:01.1: BAR 2 [io  0x0170-0x0177]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.878565] pci 0000:00:01.1: BAR 3 [io  0x0376]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.806711] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.881202] 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.807926] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.810310] acpiphp: Slot [3] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.811990] acpiphp: Slot [4] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.887254] pci 0000:00:01.2: BAR 4 [io  0xc100-0xc11f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.812987] acpiphp: Slot [5] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.814986] acpiphp: Slot [6] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.815987] acpiphp: Slot [7] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.816987] acpiphp: Slot [8] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.892905] 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.817992] acpiphp: Slot [9] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.819986] acpiphp: Slot [10] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.820969] acpiphp: Slot [11] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.896874] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.821980] acpiphp: Slot [12] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.822973] acpiphp: Slot [13] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.898603] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.823998] acpiphp: Slot [14] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.824980] acpiphp: Slot [15] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.901429] 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.826018] acpiphp: Slot [16] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.826985] acpiphp: Slot [17] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.827982] acpiphp: Slot [18] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.904605] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.828981] acpiphp: Slot [19] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.829972] acpiphp: Slot [20] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.830967] acpiphp: Slot [21] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.831980] acpiphp: Slot [22] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.910531] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.832966] acpiphp: Slot [23] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.833970] acpiphp: Slot [24] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.834966] acpiphp: Slot [25] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.835967] acpiphp: Slot [26] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.836981] acpiphp: Slot [27] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.837971] acpiphp: Slot [28] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.838967] acpiphp: Slot [29] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.918606] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.839967] acpiphp: Slot [30] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.840969] acpiphp: Slot [31] registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.920819] 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.841957] PCI host bridge to bus 0000:00[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.842930] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.923754] 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.844922] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.846922] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.927482] pci 0000:00:03.0: BAR 0 [io  0xc120-0xc13f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.848922] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.930564] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.850926] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.852923] pci_bus 0000:00: root bus resource [bus 00-ff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.855635] 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.936567] pci 0000:00:03.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.858632] 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.940412] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.862142] 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.944882] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.948566] pci 0000:00:04.0: BAR 0 [io  0xc140-0xc15f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.868885] pci 0000:00:01.1: BAR 4 [io  0xc1e0-0xc1ef][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.952482] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.872633] pci 0000:00:01.1: BAR 0 [io  0x01f0-0x01f7]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.873928] pci 0000:00:01.1: BAR 1 [io  0x03f6]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.875926] pci 0000:00:01.1: BAR 2 [io  0x0170-0x0177]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.957566] pci 0000:00:04.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.877926] pci 0000:00:01.1: BAR 3 [io  0x0376]: legacy IDE quirk[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.963924] 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.880606] 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.966561] pci 0000:00:05.0: BAR 0 [io  0xc080-0xc0bf][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.968562] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.886928] pci 0000:00:01.2: BAR 4 [io  0xc100-0xc11f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.973561] pci 0000:00:05.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.893257] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.980000] 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.897398] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.983434] pci 0000:00:06.0: BAR 0 [io  0xc160-0xc17f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.898970] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.985563] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.901926] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.991565] pci 0000:00:06.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.905989] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.912016] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    0.998762] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.002131] pci 0000:00:07.0: BAR 0 [io  0xc180-0xc19f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.004563] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.920989] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.009589] pci 0000:00:07.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.923178] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.015876] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.926176] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.018561] pci 0000:00:08.0: BAR 0 [io  0xc000-0xc07f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.020561] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.929926] pci 0000:00:03.0: BAR 0 [io  0xc120-0xc13f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.933926] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.025350] pci 0000:00:08.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.031314] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.940928] pci 0000:00:03.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.033560] pci 0000:00:09.0: BAR 0 [io  0xc1a0-0xc1bf][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.035560] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.944827] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.039561] pci 0000:00:09.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.950363] 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.953926] pci 0000:00:04.0: BAR 0 [io  0xc140-0xc15f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.957787] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.042560] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.048311] 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.963927] pci 0000:00:04.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.052001] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.056564] pci 0000:00:0a.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.972489] 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.974922] pci 0000:00:05.0: BAR 0 [io  0xc080-0xc0bf][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.976921] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.062833] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.066374] pci 0000:00:0b.0: BAR 0 [io  0xc0c0-0xc0ff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.982922] pci 0000:00:05.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.069267] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.990035] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.074565] pci 0000:00:0b.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.992921] pci 0000:00:06.0: BAR 0 [io  0xc160-0xc17f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    0.995924] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.080821] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.083563] pci 0000:00:0c.0: BAR 0 [io  0xc1c0-0xc1df][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.001889] pci 0000:00:06.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.086541] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.008218] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.092563] pci 0000:00:0c.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.011646] pci 0000:00:07.0: BAR 0 [io  0xc180-0xc19f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.013923] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.100070] ACPI: PCI: Interrupt link LNKA configured for IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.101860] ACPI: PCI: Interrupt link LNKB configured for IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.019922] pci 0000:00:07.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.103899] ACPI: PCI: Interrupt link LNKC configured for IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.105847] ACPI: PCI: Interrupt link LNKD configured for IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.107723] ACPI: PCI: Interrupt link LNKS configured for IRQ 9[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.027573] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.110139] iommu: Default domain type: Translated[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.111563] iommu: DMA domain TLB invalidation policy: lazy mode[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.030877] pci 0000:00:08.0: BAR 0 [io  0xc000-0xc07f][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.113603] ACPI: bus type USB registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.032923] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.115599] usbcore: registered new interface driver usbfs[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.117580] usbcore: registered new interface driver hub[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.119578] usbcore: registered new device driver usb[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.038820] pci 0000:00:08.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.122053] NetLabel: Initializing[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.123593] NetLabel:  domain hash size = 128[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.125563] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.046469] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.127593] NetLabel:  unlabeled traffic allowed by default[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.129564] PCI: Using ACPI for IRQ routing[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.049725] pci 0000:00:09.0: BAR 0 [io  0xc1a0-0xc1bf][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.132740] pci 0000:00:02.0: vgaarb: setting as boot VGA device[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.051924] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.133548] pci 0000:00:02.0: vgaarb: bridge control possible[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.133548] 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 # [    1.056921] pci 0000:00:09.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.133568] vgaarb: loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.134674] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.136562] hpet0: 3 comparators, 64-bit 100.000000 MHz counter[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.141626] clocksource: Switched to clocksource kvm-clock[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.060736] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.147188] VFS: Disk quotas dquot_6.6.0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.150435] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.155678] pnp: PnP ACPI init[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.065697] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.159106] pnp: PnP ACPI: found 6 devices[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.068877] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.072523] pci 0000:00:0a.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.169235] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.175828] clocksource: Switched to clocksource acpi_pm[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.180130] NET: Registered PF_INET protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.078660] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.080922] pci 0000:00:0b.0: BAR 0 [io  0xc0c0-0xc0ff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.184262] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.083923] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.088922] pci 0000:00:0b.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.206531] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.095572] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.212784] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.098538] pci 0000:00:0c.0: BAR 0 [io  0xc1c0-0xc1df][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.218770] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.100920] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.224712] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.104923] pci 0000:00:0c.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.230522] TCP: Hash tables configured (established 8192 bind 8192)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.235569] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.241288] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.113735] ACPI: PCI: Interrupt link LNKA configured for IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.115147] ACPI: PCI: Interrupt link LNKB configured for IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.246539] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.117235] ACPI: PCI: Interrupt link LNKC configured for IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.251958] NET: Registered PF_UNIX/PF_LOCAL protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.119239] ACPI: PCI: Interrupt link LNKD configured for IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.256494] NET: Registered PF_XDP protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.121090] ACPI: PCI: Interrupt link LNKS configured for IRQ 9[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.260525] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.123500] iommu: Default domain type: Translated[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.265258] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.124921] iommu: DMA domain TLB invalidation policy: lazy mode[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.126956] ACPI: bus type USB registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.269992] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.127958] usbcore: registered new interface driver usbfs[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.275305] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.128947] usbcore: registered new interface driver hub[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.130939] usbcore: registered new device driver usb[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.280527] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.132408] NetLabel: Initializing[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.133920] NetLabel:  domain hash size = 128[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.285918] pci 0000:00:01.0: PIIX3: Enabling Passive Release[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.134924] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.290547] pci 0000:00:00.0: Limiting direct PCI/PCI transfers[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.136958] NetLabel:  unlabeled traffic allowed by default[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.138923] PCI: Using ACPI for IRQ routing[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.297532] ACPI: \_SB_.LNKD: Enabled at IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.141092] pci 0000:00:02.0: vgaarb: setting as boot VGA device[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.304671] PCI: CLS 0 bytes, default 64[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.141909] pci 0000:00:02.0: vgaarb: bridge control possible[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.307285] Trying to unpack rootfs image as initramfs...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.141909] 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 # [    1.141932] vgaarb: loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.312557] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.143044] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.144923] hpet0: 3 comparators, 64-bit 100.000000 MHz counter[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.148988] clocksource: Switched to clocksource kvm-clock[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.153018] VFS: Disk quotas dquot_6.6.0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.155671] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.159732] pnp: PnP ACPI init[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.162469] pnp: PnP ACPI: found 6 devices[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.348754] Initialise system trusted keyrings[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.171241] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.176540] clocksource: Switched to clocksource acpi_pm[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.354714] workingset: timestamp_bits=40 max_order=18 bucket_order=0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.179823] NET: Registered PF_INET protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.183231] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.198103] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.202958] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.384497] Key type asymmetric registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.207630] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.212445] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.392486] Asymmetric key parser 'x509' registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.216996] TCP: Hash tables configured (established 8192 bind 8192)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.220767] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.225107] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.402516] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.229049] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.233367] NET: Registered PF_UNIX/PF_LOCAL protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.236696] NET: Registered PF_XDP protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.415582] io scheduler mq-deadline registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.239649] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.418897] io scheduler kyber registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.243425] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.247216] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.426589] Freeing initrd memory: 12300K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.251158] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.429946] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.255160] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.434616] 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.259499] pci 0000:00:01.0: PIIX3: Enabling Passive Release[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.262955] pci 0000:00:00.0: Limiting direct PCI/PCI transfers[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.440739] Linux agpgart interface v0.103[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.443534] ACPI: bus type drm_connector registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.268059] ACPI: \_SB_.LNKD: Enabled at IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.447111] usbcore: registered new interface driver usbserial_generic[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.273639] PCI: CLS 0 bytes, default 64[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.450910] usbserial: USB Serial support registered for generic[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.276495] Trying to unpack rootfs image as initramfs...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.454669] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.459578] drop_monitor: Initializing network drop monitor service[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.281475] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.463320] NET: Registered PF_INET6 protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.467330] Segment Routing with IPv6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.469929] In-situ OAM (IOAM) with IPv6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.472886] IPI shorthand broadcast: enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.478147] registered taskstats version 1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.481135] Loading compiled-in X.509 certificates[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.489800] Demotion targets for Node 0: null[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.492615] Key type .fscrypt registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.495057] Key type fscrypt-provisioning registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.498188] PM:   Magic number: 6:608:846[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.321860] Initialise system trusted keyrings[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.501788] RAS: Correctable Errors collector initialized.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.328487] workingset: timestamp_bits=40 max_order=18 bucket_order=0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.508686] Unstable clock detected, switching default tracing clock to "global"[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.508686] If you want to keep using the local clock, then add:[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.508686]   "trace_clock=local"[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.508686] on the kernel command line[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.519710] clk: Disabling unused clocks[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.522513] PM: genpd: Disabling unused power domains[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.526837] Freeing unused decrypted memory: 2028K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.530157] Freeing unused kernel image (initmem) memory: 3408K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.533710] Write protecting the kernel read-only data: 30720k[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.537689] Freeing unused kernel image (rodata/data gap) memory: 1756K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.365556] Key type asymmetric registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.374399] Asymmetric key parser 'x509' registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.384729] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.398469] io scheduler mq-deadline registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.402035] io scheduler kyber registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.588131] x86/mm: Checked W+X mappings: passed, no W+X pages found.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.412646] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.591915] Run /init as init process[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.425966] 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.434180] Freeing initrd memory: 12300K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.439633] Linux agpgart interface v0.103[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.443024] ACPI: bus type drm_connector registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.447476] usbcore: registered new interface driver usbserial_generic[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.451610] usbserial: USB Serial support registered for generic[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.455918] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.461028] drop_monitor: Initializing network drop monitor service[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.465177] NET: Registered PF_INET6 protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.469540] Segment Routing with IPv6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.645920] 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.472396] In-situ OAM (IOAM) with IPv6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.475830] IPI shorthand broadcast: enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.481708] registered taskstats version 1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.485115] Loading compiled-in X.509 certificates[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.494208] Demotion targets for Node 0: null[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.497036] Key type .fscrypt registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.499539] Key type fscrypt-provisioning registered[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.502761] PM:   Magic number: 6:608:846[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.506182] RAS: Correctable Errors collector initialized.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.513695] Unstable clock detected, switching default tracing clock to "global"[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.513695] If you want to keep using the local clock, then add:[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.513695]   "trace_clock=local"[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.513695] on the kernel command line[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.526474] clk: Disabling unused clocks[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.529363] PM: genpd: Disabling unused power domains[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.533969] Freeing unused decrypted memory: 2028K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.537539] Freeing unused kernel image (initmem) memory: 3408K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.541118] Write protecting the kernel read-only data: 30720k[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.545621] Freeing unused kernel image (rodata/data gap) memory: 1756K[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.597543] x86/mm: Checked W+X mappings: passed, no W+X pages found.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.601556] Run /init as init process[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.655398] 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.864795] 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.888174] uhci_hcd 0000:00:01.2: UHCI Host Controller[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.892052] SCSI subsystem initialized[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.906543] serio: i8042 KBD port at 0x60,0x64 irq 1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.910082] 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.917232] ACPI: \_SB_.LNKC: Enabled at IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.927566] uhci_hcd 0000:00:01.2: detected 2 ports[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.931034] serio: i8042 AUX port at 0x60,0x64 irq 12[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.946620] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.959729] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.12[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.965560] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.970497] usb usb1: Product: UHCI Host Controller[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.974052] usb usb1: Manufacturer: Linux 6.12.62 uhci_hcd[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.977994] usb usb1: SerialNumber: 0000:00:01.2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.988652] ACPI: \_SB_.LNKA: Enabled at IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    1.995306] scsi host0: ata_piix[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.002524] scsi host1: ata_piix[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.008707] ACPI: \_SB_.LNKB: Enabled at IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.011746] 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 # [    2.016565] 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 # [    2.024650] hub 1-0:1.0: USB hub found[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.030693] hub 1-0:1.0: 2 ports detected[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.894146] ACPI: \_SB_.LNKC: Enabled at IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.897658] 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.928846] SCSI subsystem initialized[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.938139] uhci_hcd 0000:00:01.2: UHCI Host Controller[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.950944] serio: i8042 KBD port at 0x60,0x64 irq 1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.960423] 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.965087] serio: i8042 AUX port at 0x60,0x64 irq 12[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.979243] ACPI: \_SB_.LNKA: Enabled at IRQ 10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.987916] uhci_hcd 0000:00:01.2: detected 2 ports[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    1.994668] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.181954] ata2: found unknown device (class 0)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.007507] ACPI: \_SB_.LNKB: Enabled at IRQ 11[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.010573] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.12[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.187815] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.015619] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.019859] usb usb1: Product: UHCI Host Controller[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.196055] 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[2mclient # [    2.022804] usb usb1: Manufacturer: Linux 6.12.62 uhci_hcd[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.026048] usb usb1: SerialNumber: 0000:00:01.2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.031401] scsi host0: ata_piix[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.035624] scsi host1: ata_piix[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.044902] 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 # [    2.049357] 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 # [    2.061155] hub 1-0:1.0: USB hub found[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.069707] hub 1-0:1.0: 2 ports detected[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.260662] usb 1-1: new full-speed USB device number 2 using uhci_hcd[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.331637] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.373921] virtio_blk virtio5: 1/0/0 default/read/poll queues[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.207770] ata2: found unknown device (class 0)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.213248] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.221142] 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 # [    2.413014] 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 # [    2.422251] netfs: FS-Cache loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.436128] 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 # [    2.440508] cdrom: Uniform CD-ROM driver Revision: 3.20[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.452506] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.457466] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.461809] usb 1-1: Product: QEMU USB Tablet[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.464608] usb 1-1: Manufacturer: QEMU[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.468339] usb 1-1: SerialNumber: 28754-0000:00:01.2-1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.294451] usb 1-1: new full-speed USB device number 2 using uhci_hcd[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.472252] 9pnet: Installing 9P2000 support[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.347560] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.522690] hid: raw HID events driver (C) Jiri Kosina[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.371684] virtio_blk virtio5: 1/0/0 default/read/poll queues[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.549534] usbcore: registered new interface driver usbhid[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.553019] usbhid: USB HID core driver[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.559919] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    2.574815] 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 # [    2.405893] netfs: FS-Cache loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.408986] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.452090] 9pnet: Installing 9P2000 support[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.461903] 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 # [    2.467020] cdrom: Uniform CD-ROM driver Revision: 3.20[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.471527] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.478056] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.483803] usb 1-1: Product: QEMU USB Tablet[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.487490] usb 1-1: Manufacturer: QEMU[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.490874] usb 1-1: SerialNumber: 28754-0000:00:01.2-1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.546152] hid: raw HID events driver (C) Jiri Kosina[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.577906] usbcore: registered new interface driver usbhid[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.581168] usbhid: USB HID core driver[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.585953] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.600708] 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 # [    3.099224] EXT4-fs (vda): mounted filesystem 069dba71-c190-4124-a059-4338a1e8327d r/w with ordered data mode. Quota mode: none.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.935661] EXT4-fs (vda): mounted filesystem 2bf68771-21da-49aa-8efa-0256cf5823b1 r/w with ordered data mode. Quota mode: none.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    3.126738] 9p: Installing v9fs 9p2000 file system support[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    2.967213] 9p: Installing v9fs 9p2000 file system support[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    3.460520] EXT4-fs (vda): re-mounted 069dba71-c190-4124-a059-4338a1e8327d.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    3.466615] booting system configuration /nix/store/aycj65q2vjaiwwbk57wba4kaxs7226vn-nixos-system-docsserver-test[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    3.302575] EXT4-fs (vda): re-mounted 2bf68771-21da-49aa-8efa-0256cf5823b1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    3.310179] booting system configuration /nix/store/001k9r4jinw78ih5x5hxggb82ynlbkqn-nixos-system-client-test[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.777190] systemd[1]: Inserted module 'autofs4'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.608614] systemd[1]: Inserted module 'autofs4'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.842937] 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 # [    5.861681] systemd[1]: Detected virtualization kvm.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.865335] systemd[1]: Detected architecture x86-64.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.869058] systemd[1]: Detected first boot.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.680435] 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 # [    5.876894] systemd[1]: Initializing machine ID from random generator.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.703492] systemd[1]: Detected virtualization kvm.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.707516] systemd[1]: Detected architecture x86-64.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.711484] systemd[1]: Detected first boot.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.722156] systemd[1]: Initializing machine ID from random generator.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    5.905212] systemd[1]: Hostname set to <docsserver>.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.744502] systemd[1]: Hostname set to <client>.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.051626] systemd[1]: bpf-restrict-fs: LSM BPF program attached[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    5.885804] systemd[1]: bpf-restrict-fs: LSM BPF program attached[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    6.259921] systemd[1]: Applying preset policy.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.086368] systemd[1]: Applying preset policy.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.086300] systemd[1]: Populated /etc with preset unit settings.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    6.919362] systemd[1]: Populated /etc with preset unit settings.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.673588] systemd[1]: /etc/systemd/system/centjes-docs-site-production.service:3: Invalid URL, ignoring: /nix/store/pfg8hayvf5qq1axqbh3zkfrk33031syf-settings-check[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.612089] systemd[1]: Queued start job for default target Multi-User System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.825265] systemd[1]: Queued start job for default target Multi-User System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.659746] systemd[1]: Created slice Slice /system/getty.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.664226] systemd[1]: Created slice Slice /system/modprobe.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.668467] systemd[1]: Created slice User and Session Slice.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.672535] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.676871] systemd[1]: Started Forward Password Requests to Wall Directory Watch.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.680869] systemd[1]: Expecting device /dev/hvc0...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.683666] systemd[1]: Expecting device /dev/ttyS0...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.686961] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.866035] systemd[1]: Created slice Slice /system/getty.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.691423] systemd[1]: Reached target Local Encrypted Volumes.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.870597] systemd[1]: Created slice Slice /system/modprobe.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.695190] systemd[1]: Reached target Virtual Machines and Containers.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.875065] systemd[1]: Created slice User and Session Slice.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.699527] systemd[1]: Reached target Path Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.879253] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.702940] systemd[1]: Reached target Remote File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.706601] systemd[1]: Reached target Slice Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.883089] systemd[1]: Started Forward Password Requests to Wall Directory Watch.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.709768] systemd[1]: Reached target Swaps.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.887631] systemd[1]: Expecting device /dev/hvc0...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.890301] systemd[1]: Expecting device /dev/ttyS0...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.893312] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.719030] systemd[1]: Listening on Process Core Dump Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.896939] systemd[1]: Reached target Local Encrypted Volumes.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.900612] systemd[1]: Reached target Virtual Machines and Containers.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.903852] systemd[1]: Reached target Path Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.726634] systemd[1]: Listening on Credential Encryption/Decryption.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.906631] systemd[1]: Reached target Remote File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.731510] systemd[1]: Listening on Journal Audit Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.909930] systemd[1]: Reached target Slice Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.735113] systemd[1]: Listening on Journal Socket (/dev/log).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.912621] systemd[1]: Reached target Swaps.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.738746] systemd[1]: Listening on Journal Sockets.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.742488] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.921112] systemd[1]: Listening on Process Core Dump Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.746519] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.751751] systemd[1]: Listening on udev Control Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.928327] systemd[1]: Listening on Credential Encryption/Decryption.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.754854] systemd[1]: Listening on udev Kernel Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.932827] systemd[1]: Listening on Journal Audit Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.936534] systemd[1]: Listening on Journal Socket (/dev/log).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.939991] systemd[1]: Listening on Journal Sockets.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.764551] systemd[1]: Mounting Huge Pages File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.943325] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.946907] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.773626] systemd[1]: Mounting POSIX Message Queue File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.952621] systemd[1]: Listening on udev Control Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.955727] systemd[1]: Listening on udev Kernel Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.786408] systemd[1]: Mounting Kernel Debug File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.963616] systemd[1]: Mounting Huge Pages File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.971651] systemd[1]: Mounting POSIX Message Queue File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.801813] systemd[1]: Mounting Kernel Trace File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.980768] systemd[1]: Mounting Kernel Debug File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    7.998616] systemd[1]: Mounting Kernel Trace File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.821693] systemd[1]: Starting Create List of Static Device Nodes...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.827573] 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 # [    8.012931] systemd[1]: Starting Create List of Static Device Nodes...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.018817] systemd[1]: Load Kernel Module 9pnet_virtio was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!9pnet_virtio).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.856205] systemd[1]: Starting Load Kernel Module configfs...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.035633] systemd[1]: Starting Load Kernel Module configfs...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.860916] 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 # [    8.042152] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.872554] systemd[1]: Starting Load Kernel Module efi_pstore...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.883038] systemd[1]: Starting Load Kernel Module fuse...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.061865] systemd[1]: Starting Load Kernel Module efi_pstore...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.888892] 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 # [    8.072734] systemd[1]: Starting Load Kernel Module fuse...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.078309] 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[2mclient # [    7.901559] 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 # [    8.087795] systemd[1]: Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.924993] systemd[1]: Starting Journal Service...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.110658] systemd[1]: Starting Journal Service...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.936845] systemd[1]: Starting Load Kernel Modules...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.121571] systemd[1]: Starting Load Kernel Modules...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.950510] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.132043] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.144599] systemd[1]: Starting Remount Root and Kernel File Systems...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.968062] systemd[1]: Starting Remount Root and Kernel File Systems...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.151487] 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 # [    7.975501] 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 # [    8.166964] systemd[1]: Starting Coldplug All udev Devices...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    7.993501] systemd[1]: Starting Coldplug All udev Devices...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.181730] systemd[1]: Mounted Huge Pages File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.010054] systemd[1]: Mounted Huge Pages File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.188112] systemd[1]: Mounted POSIX Message Queue File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.017190] systemd[1]: Mounted POSIX Message Queue File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.194810] systemd[1]: Mounted Kernel Debug File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.200461] systemd[1]: Mounted Kernel Trace File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.023870] systemd[1]: Mounted Kernel Debug File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.030724] systemd[1]: Mounted Kernel Trace File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.213618] systemd[1]: Finished Create List of Static Device Nodes.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.041982] systemd[1]: Finished Create List of Static Device Nodes.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.221002] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.230083] systemd[1]: Finished Load Kernel Module efi_pstore.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.237603] systemd[1]: modprobe@configfs.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.060694] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.247105] systemd[1]: Finished Load Kernel Module configfs.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.261699] systemd[1]: Mounting Kernel Configuration File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.096049] systemd[1]: modprobe@configfs.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.105576] systemd[1]: Finished Load Kernel Module configfs.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.281233] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.118048] systemd[1]: Mounting Kernel Configuration File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.306330] systemd[1]: Mounted Kernel Configuration File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.324033] fuse: init (API version 7.41)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.152455] systemd[1]: Mounted Kernel Configuration File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.160647] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.342674] EXT4-fs (vda): re-mounted 069dba71-c190-4124-a059-4338a1e8327d.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.347066] loop: module loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.172508] systemd[1]: Finished Load Kernel Module efi_pstore.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.353068] systemd[1]: modprobe@fuse.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.363060] systemd[1]: Finished Load Kernel Module fuse.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.196551] EXT4-fs (vda): re-mounted 2bf68771-21da-49aa-8efa-0256cf5823b1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.375675] systemd[1]: Mounting FUSE Control File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.378328] systemd-journald[384]: Collecting audit messages is enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.211219] fuse: init (API version 7.41)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.214546] loop: module loaded[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.392054] systemd[1]: Finished Load Kernel Modules.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.402874] systemd[1]: Finished Remount Root and Kernel File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.234902] systemd[1]: Finished Remount Root and Kernel File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.240078] systemd-journald[381]: Collecting audit messages is enabled.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.421210] systemd[1]: Starting Firewall...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.246107] systemd[1]: modprobe@fuse.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.426662] 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 # [    8.254609] systemd[1]: Finished Load Kernel Module fuse.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.265176] systemd[1]: Finished Load Kernel Modules.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.449814] systemd[1]: Starting Load/Save OS Random Seed...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.279234] systemd[1]: Mounting FUSE Control File System...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.464313] systemd[1]: Starting Apply Kernel Variables...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.293906] systemd[1]: Starting Firewall...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.470847] 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 # [    8.300440] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.487888] systemd[1]: Mounted FUSE Control File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.327197] systemd[1]: Starting Load/Save OS Random Seed...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.341254] systemd[1]: Starting Apply Kernel Variables...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.346920] 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 # [    8.359701] systemd[1]: Mounted FUSE Control File System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.416549] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.608656] systemd[1]: Finished Load/Save OS Random Seed.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.434512] systemd[1]: Starting Create Static Device Nodes in /dev...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.616929] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.628727] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.643192] systemd[1]: Reached target First Boot Complete.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.473016] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.658178] systemd[1]: Starting Create Static Device Nodes in /dev...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.492938] systemd[1]: Finished Load/Save OS Random Seed.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.501127] systemd[1]: Reached target First Boot Complete.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.684116] systemd[1]: Finished Apply Kernel Variables.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.543127] systemd[1]: Finished Apply Kernel Variables.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.647561] systemd[1]: Finished Create Static Device Nodes in /dev.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.827647] systemd[1]: Finished Create Static Device Nodes in /dev.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.655827] systemd[1]: Reached target Preparation for Local File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.835040] systemd[1]: Reached target Preparation for Local File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.849614] systemd[1]: Starting Rule-based Manager for Device Events and Files...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.672474] systemd[1]: Starting Rule-based Manager for Device Events and Files...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.081796] systemd[1]: Started Journal Service.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.575805] systemd-modules-load[385]: Inserted module 'loop'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.929187] systemd[1]: Started Journal Service.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.381582] systemd-modules-load[382]: Inserted module 'loop'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.596412] systemd-oomd[386]: [0;1;38:5:185mNo swap; memory pressure usage will be degraded[0m[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.395534] systemd-oomd[383]: [0;1;38:5:185mNo swap; memory pressure usage will be degraded[0m[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.621461] systemd-udevd[414]: Using default interface naming scheme 'v258'.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.416461] systemd-udevd[406]: Using default interface naming scheme 'v258'.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.640175] systemd[1]: Starting Flush Journal to Persistent Storage...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.432216] systemd[1]: Starting Flush Journal to Persistent Storage...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.466311] systemd[1]: Mounting /run/wrappers...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    8.679697] systemd[1]: Mounting /run/wrappers...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.234205] systemd-journald[384]: Received client request to flush runtime journal.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.061888] systemd-journald[381]: Received client request to flush runtime journal.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.121374] systemd[1]: Mounted /run/wrappers.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.135962] systemd[1]: Reached target Local File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.152406] systemd[1]: Listening on Boot Entries Service Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.164611] systemd[1]: Starting Create SUID/SGID Wrappers...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    8.970863] systemd[1]: Mounted /run/wrappers.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.182764] 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 # [    8.991701] systemd[1]: Reached target Local File Systems.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.207936] systemd[1]: Starting Save Transient machine-id to Disk...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.226782] systemd[1]: Finished Coldplug All udev Devices.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.013340] systemd[1]: Listening on Boot Entries Service Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.026838] systemd[1]: Starting Create SUID/SGID Wrappers...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.245360] systemd[1]: Started Rule-based Manager for Device Events and Files.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.046516] 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 # [    9.266289] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.281645] systemd[1]: Finished Save Transient machine-id to Disk.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.068342] systemd[1]: Starting Save Transient machine-id to Disk...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.298521] systemd[1]: Finished Flush Journal to Persistent Storage.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.084585] systemd[1]: Started Rule-based Manager for Device Events and Files.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.312391] systemd[1]: Starting Create System Files and Directories...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.105235] systemd[1]: Finished Coldplug All udev Devices.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.117321] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.136193] systemd[1]: Finished Save Transient machine-id to Disk.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.148562] systemd[1]: Finished Flush Journal to Persistent Storage.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.163764] systemd[1]: Starting Create System Files and Directories...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.386359] systemd[1]: Finished Create System Files and Directories.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.404533] systemd[1]: Starting Rebuild Journal Catalog...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.422609] systemd[1]: Starting Record System Boot/Shutdown in UTMP...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.242735] systemd[1]: Finished Create System Files and Directories.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.261509] systemd[1]: Starting Rebuild Journal Catalog...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.276350] systemd[1]: Starting Record System Boot/Shutdown in UTMP...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.546753] systemd[1]: Finished Record System Boot/Shutdown in UTMP.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.391643] systemd[1]: Finished Record System Boot/Shutdown in UTMP.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.654680] systemd[1]: Finished Rebuild Journal Catalog.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.664794] systemd[1]: Starting Update is Completed...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.503808] systemd[1]: Finished Rebuild Journal Catalog.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.521677] systemd[1]: Starting Update is Completed...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [    9.780643] systemd[1]: Finished Update is Completed.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.598880] systemd[1]: Finished Update is Completed.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.031852] systemd[1]: Found device /dev/hvc0.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.867506] systemd[1]: Found device /dev/hvc0.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.908744] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.129928] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.142305] systemd[1]: Finished Create SUID/SGID Wrappers.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.926319] systemd[1]: Finished Create SUID/SGID Wrappers.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.148514] systemd[1]: Reached target System Initialization.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.938598] systemd[1]: Reached target System Initialization.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.158475] systemd[1]: Started Discard unused filesystem blocks once a week.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.956558] systemd[1]: Started Discard unused filesystem blocks once a week.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.177604] systemd[1]: Started Daily Cleanup of Temporary Directories.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.211760] systemd[1]: Reached target Timer Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [    9.992153] systemd[1]: Started Daily Cleanup of Temporary Directories.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.029268] systemd[1]: Reached target Timer Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.247454] systemd[1]: Listening on D-Bus System Message Bus Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.045939] systemd[1]: Listening on D-Bus System Message Bus Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.077158] systemd[1]: Listening on Nix Daemon Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.285472] systemd[1]: Listening on Nix Daemon Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.096804] systemd[1]: Listening on Hostname Service Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.325842] systemd[1]: Listening on Hostname Service Socket.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.135878] systemd[1]: Reached target Socket Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.367570] systemd[1]: Reached target Socket Units.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.156826] systemd[1]: Reached target Basic System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.185429] systemd[1]: Starting Import lastlog data into lastlog2 database...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.398847] systemd[1]: Reached target Basic System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.213154] systemd[1]: Starting Name Service Cache Daemon (nsncd)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.235933] systemd[1]: Started Reset console on configuration changes.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.430628] systemd[1]: Starting Import lastlog data into lastlog2 database...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.264164] systemd[1]: Starting resolvconf update...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.477771] systemd[1]: Starting Name Service Cache Daemon (nsncd)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.277567] systemd[1]: Starting D-Bus System Message Bus...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.524755] systemd[1]: Started Reset console on configuration changes.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.303513] (udev-worker)[515]: [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 # connecting to host...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.558272] systemd[1]: Starting resolvconf update...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.342616] nsncd[590]: Apr 27 17:49:50.420 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 # [   10.371651] systemd[1]: Finished Import lastlog data into lastlog2 database.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.395630] systemd[1]: Found device /dev/ttyS0.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.573910] nsncd[600]: Apr 27 17:49:50.453 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 # [   10.421325] dbus-daemon[594]: dbus[594]: Unknown username "systemd-timesync" in message bus configuration file[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 12.07 seconds)
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: (finished: waiting for the VM to finish booting, in 12.07 seconds)
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.639668] systemd[1]: Started Centjes docs site production Service.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.448400] (udev-worker)[515]: Network interface NamePolicy= disabled on kernel command line.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.467371] systemd[1]: Started backdoor.service.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.672673] dbus-daemon[604]: dbus[604]: Unknown username "systemd-timesync" in message bus configuration file[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.481747] (udev-worker)[524]: Network interface NamePolicy= disabled on kernel command line.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # connecting to host...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.714332] systemd[1]: Starting D-Bus System Message Bus...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.509710] systemd[1]: Started Name Service Cache Daemon (nsncd).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.740795] systemd[1]: Found device /dev/ttyS0.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.535199] systemd[1]: Reached target Host and Network Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.765954] systemd[1]: Started backdoor.service.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.554836] systemd[1]: Reached target User and Group Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.586781] systemd[1]: Starting User Login Management...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.601504] systemd[1]: Started D-Bus System Message Bus.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.805650] (udev-worker)[515]: [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 # [   10.625256] systemd[1]: Stopped target Host and Network Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.640905] systemd[1]: Stopping Host and Network Name Lookups...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.849394] (udev-worker)[515]: Network interface NamePolicy= disabled on kernel command line.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.653740] systemd[1]: Stopped target User and Group Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.876854] (udev-worker)[529]: Network interface NamePolicy= disabled on kernel command line.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.675828] systemd[1]: Stopping User and Group Name Lookups...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.689519] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.900639] systemd[1]: Finished Import lastlog data into lastlog2 database.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.709798] systemd[1]: nscd.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.934927] systemd[1]: Started Name Service Cache Daemon (nsncd).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.735875] systemd[1]: Stopped Name Service Cache Daemon (nsncd).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.949410] systemd[1]: Reached target Host and Network Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.966406] systemd[1]: Reached target User and Group Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.752587] systemd[1]: Starting Name Service Cache Daemon (nsncd)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.773571] systemd-logind[620]: New seat seat0.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   10.982796] systemd[1]: Starting User Login Management...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.000329] systemd[1]: Started D-Bus System Message Bus.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.011920] systemd[1]: Stopped target Host and Network Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.787215] nsncd[674]: Apr 27 17:49:50.899 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 # [   11.024920] systemd[1]: Stopping Host and Network Name Lookups...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.816738] systemd[1]: Started User Login Management.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.041300] systemd[1]: Stopped target User and Group Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.833205] systemd[1]: Starting linger-users.service...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.840607] systemd[1]: Started Name Service Cache Daemon (nsncd).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.059347] systemd[1]: Stopping User and Group Name Lookups...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.853614] systemd[1]: Reached target Host and Network Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.864316] systemd[1]: Reached target User and Group Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.874767] systemd[1]: Finished Firewall.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.082427] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.880411] systemd[1]: linger-users.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.887336] systemd[1]: Finished linger-users.service.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.097411] systemd[1]: nscd.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.909246] systemd[1]: Finished resolvconf update.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.112905] systemd[1]: Stopped Name Service Cache Daemon (nsncd).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.913443] systemd[1]: Reached target Preparation for Network.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.921876] systemd[1]: Starting DHCP Client...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.136817] systemd[1]: Finished Firewall.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.935679] systemd[1]: Found device Virtio network device.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   10.945432] systemd[1]: Starting Address configuration of eth1...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.150281] nsncd[696]: Apr 27 17:49:51.068 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 # [   11.180373] systemd[1]: Starting Name Service Cache Daemon (nsncd)...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.194701] systemd-logind[633]: New seat seat0.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.216749] systemd[1]: Started User Login Management.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.235754] systemd[1]: Starting linger-users.service...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.246666] systemd[1]: Started Name Service Cache Daemon (nsncd).[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.259827] systemd[1]: Reached target Host and Network Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.278367] systemd[1]: Reached target User and Group Name Lookups.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.289621] systemd[1]: Found device Virtio network device.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.302591] systemd[1]: Finished resolvconf update.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.311453] systemd[1]: Reached target Preparation for Network.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.323823] systemd[1]: Starting DHCP Client...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.113490] network-addresses-eth1-start[711]: adding address 192.168.1.1/24... done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.336249] systemd[1]: Starting Address configuration of eth1...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.346751] systemd[1]: linger-users.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.359506] systemd[1]: Finished linger-users.service.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.143414] network-addresses-eth1-start[711]: adding address 2001:db8:1::1/64... done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.189429] systemd[1]: Finished Address configuration of eth1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.201556] systemd[1]: Starting Networking Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.428859] network-addresses-eth1-start[731]: adding address 192.168.1.2/24... done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.458814] network-addresses-eth1-start[731]: adding address 2001:db8:1::2/64... done[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.249700] dhcpcd[716]: dhcpcd-10.2.4 starting[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.272634] dhcpcd[730]: dev: loaded udev[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.497269] systemd[1]: Finished Address configuration of eth1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.859492] mousedev: PS/2 mouse device common for all mice[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.517750] systemd[1]: Starting Networking Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.864769] 8021q: 802.1Q VLAN Support v1.8[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.867660] 8021q: adding VLAN 0 to HW filter on device eth1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.589167] dhcpcd[739]: dhcpcd-10.2.4 starting[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.613929] dhcpcd[752]: dev: loaded udev[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.168703] 8021q: 802.1Q VLAN Support v1.8[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.175218] 8021q: adding VLAN 0 to HW filter on device eth1[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.042000] cfg80211: Loading compiled-in X.509 certificates for regulatory database[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.084437] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.090023] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.098739] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.109749] cfg80211: failed to load regulatory.db[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.555310] systemd[1]: Finished Networking Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.569348] systemd[1]: Reached target Network.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.574616] systemd[1]: Starting Permit User Sessions...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.331642] cfg80211: Loading compiled-in X.509 certificates for regulatory database[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.844942] systemd[1]: Finished Networking Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.642144] systemd[1]: Finished Permit User Sessions.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.860315] systemd[1]: Reached target Network.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.650466] systemd[1]: Started Getty on tty1.[   12.205514] 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 # [   11.865838] systemd[1]: Starting Permit User Sessions...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.658235] systemd[1]: Reached target Login Prompts.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.403002] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.410655] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.421782] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.247610] ACPI: button: Power Button [PWRF][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.430502] cfg80211: failed to load regulatory.db[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.254667] 8021q: adding VLAN 0 to HW filter on device eth0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.707836] dhcpcd[730]: eth0: waiting for carrier[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.712822] dhcpcd[730]: [0;1;31mlibudev: received NULL device[0m[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   11.720519] dhcpcd[730]: libudev: received NULL device[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.279246] rtc_cmos 00:05: RTC can wake from S4[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.459644] mousedev: PS/2 mouse device common for all mice[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.293724] Floppy drive(s): fd0 is 2.88M AMI BIOS[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.299089] parport_pc 00:03: reported by Plug and Play ACPI[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.309711] rtc_cmos 00:05: registered as rtc0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.968919] systemd[1]: Finished Permit User Sessions.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.321238] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.979495] systemd[1]: Started Getty on tty1.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.327869] rtc_cmos 00:05: setting system clock to 2026-04-27T17:49:52 UTC (1777312192)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   11.989717] systemd[1]: Reached target Login Prompts.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.336051] FDC 0 is a S82078B[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.348898] 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 # [   12.554582] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.561135] 8021q: adding VLAN 0 to HW filter on device eth0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.049984] dhcpcd[752]: eth0: waiting for carrier[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.395965] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.600709] ACPI: button: Power Button [PWRF][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.424715] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.609931] rtc_cmos 00:05: RTC can wake from S4[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.436141] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input4[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.627553] parport_pc 00:03: reported by Plug and Play ACPI[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.630750] Floppy drive(s): fd0 is 2.88M AMI BIOS[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.457970] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.642243] rtc_cmos 00:05: registered as rtc0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.467504] cryptd: max_cpu_qlen set to 1000[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.648153] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)][0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.472813] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.651566] rtc_cmos 00:05: setting system clock to 2026-04-27T17:49:52 UTC (1777312192)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.661852] FDC 0 is a S82078B[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.483150] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.677747] 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 # [   12.510865] Console: switching to colour dummy device 80x25[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.513056] [drm] Found bochs VGA, ID 0xb0c5.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.514393] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.702498] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.534922] AES CTR mode by8 optimization enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.554418] [drm] Found EDID data blob.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.563840] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.739148] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.774677] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input4[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.780678] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.789718] cryptd: max_cpu_qlen set to 1000[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.796101] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.844700] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.865959] AES CTR mode by8 optimization enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.400727] dhcpcd[752]: eth0: carrier acquired[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.872131] Console: switching to colour dummy device 80x25[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.424465] dhcpcd[752]: DUID 00:01:00:01:31:82:5e:40:52:54:00:12:34:56[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.430781] dhcpcd[752]: eth0: IAID 00:12:34:56[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.439442] dhcpcd[752]: eth0: adding address fe80::5054:ff:fe12:3456[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.966318] [drm] Found bochs VGA, ID 0xb0c5.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.967742] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   13.024060] [drm] Found EDID data blob.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   13.038950] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   12.538247] systemd[1]: Starting Virtual Console Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.626898] dhcpcd[730]: eth0: carrier acquired[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.645103] dhcpcd[730]: DUID 00:01:00:01:31:82:5e:41:52:54:00:12:34:56[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.647628] dhcpcd[730]: eth0: IAID 00:12:34:56[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.650966] dhcpcd[730]: eth0: adding address fe80::5054:ff:fe12:3456[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.726497] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.775802] systemd-logind[620]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.786973] systemd-logind[620]: Watching system buttons on /dev/input/event2 (Power Button)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.808802] systemd[1]: Starting Virtual Console Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   13.167342] dhcpcd[752]: eth0: soliciting a DHCP lease[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   13.174523] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   12.591750] fbcon: bochs-drmdrmfb (fb0) is primary device[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.389104] ppdev: user-space parallel port driver[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.417072] Console: switching to colour frame buffer device 160x50[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.588758] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.087193] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.091864] systemd[1]: Stopped Virtual Console Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.098643] systemd-logind[620]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.114294] systemd[1]: Starting Virtual Console Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.142630] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.152917] systemd[1]: Stopped Virtual Console Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.159638] systemd[1]: Starting Virtual Console Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.812228] kvm_amd: TSC scaling supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.813574] kvm_amd: Nested Virtualization enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.815005] kvm_amd: Nested Paging enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.816152] kvm_amd: LBR virtualization supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.822941] kvm_amd: Virtual VMLOAD VMSAVE supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.824579] kvm_amd: Virtual GIF supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   13.091038] fbcon: bochs-drmdrmfb (fb0) is primary device[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   13.713619] NET: Registered PF_PACKET protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   13.807914] ppdev: user-space parallel port driver[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   13.879522] Console: switching to colour frame buffer device 160x50[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   14.027694] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   13.202971] dhcpcd[752]: eth0: offered 10.0.2.15 from 10.0.2.2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   13.524294] systemd-logind[633]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   13.533845] dhcpcd[752]: eth0: probing address 10.0.2.15/24[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.882880] EDAC MC: Ver: 3.0.0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   13.539667] systemd-logind[633]: Watching system buttons on /dev/input/event2 (Power Button)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   13.547709] systemd-logind[633]: Watching system buttons on /dev/input/event5 (QEMU Virtio Keyboard)[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   13.564572] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   13.569737] systemd[1]: Stopped Virtual Console Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   13.603403] systemd[1]: Starting Virtual Console Setup...[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   14.177166] kvm_amd: TSC scaling supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   14.178851] kvm_amd: Nested Virtualization enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   14.180603] kvm_amd: Nested Paging enabled[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   14.182078] kvm_amd: LBR virtualization supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   14.192253] kvm_amd: Virtual VMLOAD VMSAVE supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   14.194021] kvm_amd: Virtual GIF supported[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.484224] dhcpcd[730]: eth0: soliciting a DHCP lease[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   14.066938] NET: Registered PF_PACKET protocol family[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.525231] dhcpcd[730]: eth0: offered 10.0.2.15 from 10.0.2.2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.530436] dhcpcd[730]: eth0: probing address 10.0.2.15/24[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   14.272686] EDAC MC: Ver: 3.0.0[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   13.992193] dhcpcd[752]: eth0: soliciting an IPv6 router[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   13.995819] dhcpcd[752]: eth0: Router Advertisement from fe80::2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   13.998499] dhcpcd[752]: eth0: adding address fec0::5054:ff:fe12:3456/64[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   14.001616] dhcpcd[752]: eth0: adding route to fec0::/64[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   14.005698] dhcpcd[752]: eth0: adding default route via fe80::2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   13.898771] systemd[1]: Finished Virtual Console Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   14.362676] systemd[1]: Finished Virtual Console Setup.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   14.981606] dhcpcd[730]: eth0: soliciting an IPv6 router[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   14.985587] dhcpcd[730]: eth0: Router Advertisement from fe80::2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   14.989296] dhcpcd[730]: eth0: adding address fec0::5054:ff:fe12:3456/64[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   14.992849] dhcpcd[730]: eth0: adding route to fec0::/64[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   14.995703] dhcpcd[730]: eth0: adding default route via fe80::2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   18.648587] dhcpcd[730]: eth0: leased 10.0.2.15 for 86400 seconds[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   18.652814] dhcpcd[730]: eth0: adding route to 10.0.2.0/24[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   18.656360] dhcpcd[730]: eth0: adding default route via 10.0.2.2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   18.812152] systemd[1]: Started DHCP Client.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   18.816967] systemd[1]: Reached target Multi-User System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mclient # [   18.819656] systemd[1]: Startup finished in 4.978s (kernel) + 13.836s (userspace) = 18.814s.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   19.197579] dhcpcd[752]: eth0: leased 10.0.2.15 for 86400 seconds[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   19.201815] dhcpcd[752]: eth0: adding route to 10.0.2.0/24[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   19.204893] dhcpcd[752]: eth0: adding default route via 10.0.2.2[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   19.361656] systemd[1]: Started DHCP Client.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   19.367774] systemd[1]: Reached target Multi-User System.[0m
[2m[vm-test-run-centjes-e2e-test] [m[2mdocsserver # [   19.370824] systemd[1]: Startup finished in 5.189s (kernel) + 14.176s (userspace) = 19.366s.[0m
[2m[vm-test-run-centjes-e2e-test] [mdocsserver: (finished: waiting for unit default.target, in 21.07 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.16 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.07 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.06 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.10 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 # [   20.318366] centjes-docs-site-production-start[612]: 192.168.1.1 - - [27/Apr/2026:17:50:00 +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 50446     0  --:--:-- --:--:-- --:--:-- 51357[0m
[2m[vm-test-run-centjes-e2e-test] [mclient: (finished: must succeed: curl docsserver:8001, in 0.19 seconds)
[2m[vm-test-run-centjes-e2e-test] [m(finished: run the VM test script, in 22.11 seconds)
[2m[vm-test-run-centjes-e2e-test] [mtest script finished in 22.26s
[2m[vm-test-run-centjes-e2e-test] [m[1m[32mcleanup[0m
[2m[vm-test-run-centjes-e2e-test] [mkill machine (pid 31)
[2m[vm-test-run-centjes-e2e-test] [mqemu-system-x86_64: terminating on signal 15 from pid 6 (/nix/store/jj6jldlw37r8yy9kc1smrax9dhnjm2x4-python3-3.13.9/bin/python3.13)
[2m[vm-test-run-centjes-e2e-test] [mkill machine (pid 9)
[2m[vm-test-run-centjes-e2e-test] [mqemu-system-x86_64: terminating on signal 15 from pid 6 (/nix/store/jj6jldlw37r8yy9kc1smrax9dhnjm2x4-python3-3.13.9/bin/python3.13)
[2m[vm-test-run-centjes-e2e-test] [mkill vlan (pid 7)
[2m[vm-test-run-centjes-e2e-test] [m(finished: cleanup, in 0.02 seconds)
[2m[post-build-hook] [m[2mUploading paths from nix-ci to cachix cache "centjes" /nix/store/hw5s85pl0wzkl3walw63rlv9c8kpv17b-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/hw5s85pl0wzkl3walw63rlv9c8kpv17b-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/hw5s85pl0wzkl3walw63rlv9c8kpv17b-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/hw5s85pl0wzkl3walw63rlv9c8kpv17b-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 0 paths...[m
[2mProgress: 10 of 11 built, 2 of 2 fetched[m