build checks.x86_64-linux.e2e-test

Reproduce this run
  1. 0.02 s $ /nix/store/vzx1mi9c0xfadmsm9dhd83d005cb1qs9-coreutils-9.8/bin/timeout --kill-after=15s 7200s /nix/store/99b1z08awpxj8b6mzggn59gp1shljnff-nix-2.34.5/bin/nix --extra-experimental-features nix-command --extra-experimental-features flakes --log-format internal-json build --no-link git+https://github.com/NorfairKing/centjes?ref=refs%2Fpull%2F2%2Fmerge&rev=d826b4fcc9fd37022acc53b94edda6d94580d29b#checks.x86_64-linux.e2e-test --print-build-logs
  2. 0.07 s warning: ignoring untrusted flake configuration setting 'extra-substituters'.
  3. 0.07 s Pass '--accept-flake-config' to trust it
  4. 0.07 s warning: ignoring untrusted flake configuration setting 'extra-trusted-public-keys'.
  5. 0.07 s Pass '--accept-flake-config' to trust it
  6. 0.08 s
  7. 0.32 s Building etc
  8. 0.73 s [post-build-hook] Uploading paths from nix-ci to cachix cache "centjes" /nix/store/k1zvssljx90qfjqbvcq8gbgdg5ss639r-etc
  9. 1.42 s [post-build-hook] Pushing 2 paths (545 are already present) using zstd to cache centjes ⏳
  10. 1.42 s [post-build-hook]
  11. 1.84 s [post-build-hook] Pushing /nix/store/k1zvssljx90qfjqbvcq8gbgdg5ss639r-etc (31.56 KiB)
  12. 1.84 s [post-build-hook] Pushing /nix/store/10zz99ch74vmfkmjjl9khlqp3iy9sg8v-fc-cache (5.77 KiB)
  13. 2.77 s [post-build-hook]
  14. 2.77 s [post-build-hook] All done.
  15. 2.78 s [post-build-hook] Uploading paths from nix-ci to the NixCI staging cache /nix/store/k1zvssljx90qfjqbvcq8gbgdg5ss639r-etc
  16. 2.82 s [post-build-hook] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
  17. 3.07 s [post-build-hook] copying 1 paths...
  18. 3.07 s [post-build-hook] copying path '/nix/store/k1zvssljx90qfjqbvcq8gbgdg5ss639r-etc' to 'https://cache.staging.nix-ci.com'...
  19. 3.28 s [post-build-hook] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
  20. 3.63 s [post-build-hook] copying 1 paths...
  21. 3.63 s [post-build-hook] copying path '/nix/store/xr10q6mnnpmmbnr4j7rhixsrb2ih670f-etc.drv' to 'https://cache.staging.nix-ci.com'...
  22. 3.83 s Progress: 1 of 8 built
  23. 3.91 s Building nixos-system-docsserver-test
  24. 3.99 s [post-build-hook] Uploading paths from nix-ci to cachix cache "centjes" /nix/store/b4ylhqbb1k81nwvff9ifybzbhdcjb9l7-nixos-system-docsserver-test
  25. 4.72 s [post-build-hook] Pushing 1 paths (568 are already present) using zstd to cache centjes ⏳
  26. 4.72 s [post-build-hook]
  27. 5.09 s [post-build-hook] Pushing /nix/store/b4ylhqbb1k81nwvff9ifybzbhdcjb9l7-nixos-system-docsserver-test (17.26 KiB)
  28. 6.11 s [post-build-hook]
  29. 6.11 s [post-build-hook] All done.
  30. 6.13 s [post-build-hook] Uploading paths from nix-ci to the NixCI staging cache /nix/store/b4ylhqbb1k81nwvff9ifybzbhdcjb9l7-nixos-system-docsserver-test
  31. 6.17 s [post-build-hook] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
  32. 6.36 s [post-build-hook] copying 1 paths...
  33. 6.36 s [post-build-hook] copying path '/nix/store/b4ylhqbb1k81nwvff9ifybzbhdcjb9l7-nixos-system-docsserver-test' to 'https://cache.staging.nix-ci.com'...
  34. 6.57 s [post-build-hook] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
  35. 6.88 s [post-build-hook] copying 1 paths...
  36. 6.88 s [post-build-hook] copying path '/nix/store/imsyrp5rn5qgc5ivqvlz9syskv7j1laz-nixos-system-docsserver-test.drv' to 'https://cache.staging.nix-ci.com'...
  37. 7.07 s Progress: 2 of 8 built
  38. 7.49 s Building closure-info
  39. 7.49 s [closure-info] structuredAttrs is enabled
  40. 7.58 s [post-build-hook] Uploading paths from nix-ci to cachix cache "centjes" /nix/store/ngsdybz5cfd5mx5iawx565xpmyvxhj1z-closure-info
  41. 8.25 s [post-build-hook] Pushing 1 paths (569 are already present) using zstd to cache centjes ⏳
  42. 8.25 s [post-build-hook]
  43. 8.64 s [post-build-hook] Pushing /nix/store/ngsdybz5cfd5mx5iawx565xpmyvxhj1z-closure-info (228.66 KiB)
  44. 9.55 s [post-build-hook]
  45. 9.55 s [post-build-hook] All done.
  46. 9.57 s [post-build-hook] Uploading paths from nix-ci to the NixCI staging cache /nix/store/ngsdybz5cfd5mx5iawx565xpmyvxhj1z-closure-info
  47. 9.60 s [post-build-hook] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
  48. 9.64 s [post-build-hook] copying 1 paths...
  49. 9.64 s [post-build-hook] copying path '/nix/store/ngsdybz5cfd5mx5iawx565xpmyvxhj1z-closure-info' to 'https://cache.staging.nix-ci.com'...
  50. 9.93 s [post-build-hook] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
  51. 10.27 s [post-build-hook] copying 1 paths...
  52. 10.29 s [post-build-hook] copying path '/nix/store/lkv7imkjz1phzvqmixr3zlkj3fkvh6hv-closure-info.drv' to 'https://cache.staging.nix-ci.com'...
  53. 10.46 s Progress: 3 of 8 built
  54. 10.54 s Building run-nixos-vm
  55. 10.60 s [post-build-hook] Uploading paths from nix-ci to cachix cache "centjes" /nix/store/51d860flx72l22ic2aqhw1gba77dq6z5-run-nixos-vm
  56. 11.18 s [post-build-hook] Pushing 1 paths (580 are already present) using zstd to cache centjes ⏳
  57. 11.18 s [post-build-hook]
  58. 11.57 s [post-build-hook] Pushing /nix/store/51d860flx72l22ic2aqhw1gba77dq6z5-run-nixos-vm (2.94 KiB)
  59. 12.53 s [post-build-hook]
  60. 12.53 s [post-build-hook] All done.
  61. 12.55 s [post-build-hook] Uploading paths from nix-ci to the NixCI staging cache /nix/store/51d860flx72l22ic2aqhw1gba77dq6z5-run-nixos-vm
  62. 12.58 s [post-build-hook] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
  63. 12.77 s [post-build-hook] copying 1 paths...
  64. 12.77 s [post-build-hook] copying path '/nix/store/51d860flx72l22ic2aqhw1gba77dq6z5-run-nixos-vm' to 'https://cache.staging.nix-ci.com'...
  65. 12.97 s [post-build-hook] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
  66. 13.29 s [post-build-hook] copying 1 paths...
  67. 13.29 s [post-build-hook] copying path '/nix/store/ddcqp6csvxjdx2qh2jmdlndm8yn1091d-run-nixos-vm.drv' to 'https://cache.staging.nix-ci.com'...
  68. 13.49 s Progress: 4 of 8 built
  69. 13.55 s Building nixos-vm
  70. 13.62 s [post-build-hook] Uploading paths from nix-ci to cachix cache "centjes" /nix/store/ncy3cfb3av7xxdlfz8x9di2x8bllk9r5-nixos-vm
  71. 14.36 s [post-build-hook] Pushing 1 paths (581 are already present) using zstd to cache centjes ⏳
  72. 14.36 s [post-build-hook]
  73. 14.73 s [post-build-hook] Pushing /nix/store/ncy3cfb3av7xxdlfz8x9di2x8bllk9r5-nixos-vm (776.00 B)
  74. 15.66 s [post-build-hook]
  75. 15.66 s [post-build-hook] All done.
  76. 15.68 s [post-build-hook] Uploading paths from nix-ci to the NixCI staging cache /nix/store/ncy3cfb3av7xxdlfz8x9di2x8bllk9r5-nixos-vm
  77. 15.72 s [post-build-hook] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
  78. 15.75 s [post-build-hook] copying 1 paths...
  79. 15.75 s [post-build-hook] copying path '/nix/store/ncy3cfb3av7xxdlfz8x9di2x8bllk9r5-nixos-vm' to 'https://cache.staging.nix-ci.com'...
  80. 15.98 s [post-build-hook] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
  81. 16.27 s [post-build-hook] copying 1 paths...
  82. 16.27 s [post-build-hook] copying path '/nix/store/a5zbl6xv4zk53h8n9iragir5ay367jbr-nixos-vm.drv' to 'https://cache.staging.nix-ci.com'...
  83. 16.45 s Progress: 5 of 8 built
  84. 16.52 s Building nixos-test-driver-centjes-e2e-test
  85. 16.66 s [nixos-test-driver-centjes-e2e-test] Running type check (enable/disable: config.skipTypeCheck)
  86. 16.66 s [nixos-test-driver-centjes-e2e-test] See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck
  87. 21.87 s [nixos-test-driver-centjes-e2e-test] Success: no issues found in 1 source file
  88. 22.04 s [nixos-test-driver-centjes-e2e-test] additionally exposed symbols:
  89. 22.04 s [nixos-test-driver-centjes-e2e-test] ,
  90. 22.04 s [nixos-test-driver-centjes-e2e-test] ,
  91. 22.04 s [nixos-test-driver-centjes-e2e-test] 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
  92. 22.08 s [nixos-test-driver-centjes-e2e-test] Linting test script (enable/disable: config.skipLint)
  93. 22.08 s [nixos-test-driver-centjes-e2e-test] See https://nixos.org/manual/nixos/stable/#test-opt-skipLint
  94. 22.20 s [post-build-hook] Uploading paths from nix-ci to cachix cache "centjes" /nix/store/37c0smrk6sidn7q1dydyfklxm5x48li1-nixos-test-driver-centjes-e2e-test
  95. 23.09 s [post-build-hook] Pushing 1 paths (633 are already present) using zstd to cache centjes ⏳
  96. 23.09 s [post-build-hook]
  97. 23.46 s [post-build-hook] Pushing /nix/store/37c0smrk6sidn7q1dydyfklxm5x48li1-nixos-test-driver-centjes-e2e-test (1.84 KiB)
  98. 24.55 s [post-build-hook]
  99. 24.55 s [post-build-hook] All done.
  100. 24.57 s [post-build-hook] Uploading paths from nix-ci to the NixCI staging cache /nix/store/37c0smrk6sidn7q1dydyfklxm5x48li1-nixos-test-driver-centjes-e2e-test
  101. 24.61 s [post-build-hook] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
  102. 24.64 s [post-build-hook] copying 1 paths...
  103. 24.64 s [post-build-hook] copying path '/nix/store/37c0smrk6sidn7q1dydyfklxm5x48li1-nixos-test-driver-centjes-e2e-test' to 'https://cache.staging.nix-ci.com'...
  104. 24.96 s [post-build-hook] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
  105. 25.31 s [post-build-hook] copying 1 paths...
  106. 25.32 s [post-build-hook] copying path '/nix/store/wh0h0hfm7000xf5hqh1j3sa9wl1v71zs-nixos-test-driver-centjes-e2e-test.drv' to 'https://cache.staging.nix-ci.com'...
  107. 25.52 s Progress: 6 of 8 built
  108. 25.65 s Building vm-test-run-centjes-e2e-test
  109. 25.96 s [vm-test-run-centjes-e2e-test] Machine state will be reset. To keep it, pass --keep-vm-state
  110. 25.96 s [vm-test-run-centjes-e2e-test] start all VLans
  111. 25.96 s [vm-test-run-centjes-e2e-test] start vlan
  112. 25.96 s [vm-test-run-centjes-e2e-test] running vlan (pid 7; ctl /build/vde1.ctl)
  113. 25.96 s [vm-test-run-centjes-e2e-test] (finished: start all VLans, in 0.00 seconds)
  114. 25.96 s [vm-test-run-centjes-e2e-test] Test will time out and terminate in 3600 seconds
  115. 25.96 s [vm-test-run-centjes-e2e-test] run the VM test script
  116. 25.96 s [vm-test-run-centjes-e2e-test] additionally exposed symbols:
  117. 25.96 s [vm-test-run-centjes-e2e-test] client, docsserver,
  118. 25.96 s [vm-test-run-centjes-e2e-test] vlan1,
  119. 25.96 s [vm-test-run-centjes-e2e-test] 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
  120. 25.96 s [vm-test-run-centjes-e2e-test] docsserver: starting vm
  121. 25.98 s [vm-test-run-centjes-e2e-test] mke2fs 1.47.3 (8-Jul-2025)
  122. 26.11 s [vm-test-run-centjes-e2e-test] docsserver # Disk image does not exist, creating the virtualisation disk image...
  123. 26.11 s [vm-test-run-centjes-e2e-test] docsserver # Formatting '/build/vm-state-docsserver/tmp.EODLZvN0xR', fmt=raw size=1073741824
  124. 26.11 s [vm-test-run-centjes-e2e-test] docsserver # Discarding device blocks: 0/262144 done
  125. 26.11 s [vm-test-run-centjes-e2e-test] docsserver # Creating filesystem with 262144 4k blocks and 65536 inodes
  126. 26.11 s [vm-test-run-centjes-e2e-test] docsserver # Filesystem UUID: a58cf4d2-0219-4a8c-84a7-269dfc704e6c
  127. 26.11 s [vm-test-run-centjes-e2e-test] docsserver # Superblock backups stored on blocks:
  128. 26.11 s [vm-test-run-centjes-e2e-test] docsserver # 32768, 98304, 163840, 229376
  129. 26.11 s [vm-test-run-centjes-e2e-test] docsserver #
  130. 26.11 s [vm-test-run-centjes-e2e-test] docsserver # Allocating group tables: 0/8 done
  131. 26.11 s [vm-test-run-centjes-e2e-test] docsserver: QEMU running (pid 9)
  132. 26.11 s [vm-test-run-centjes-e2e-test] docsserver # Writing inode tables: 0/8 done
  133. 26.11 s [vm-test-run-centjes-e2e-test] docsserver # Creating journal (8192 blocks): done
  134. 26.11 s [vm-test-run-centjes-e2e-test] docsserver # Writing superblocks and filesystem accounting information: 0/8 done
  135. 26.11 s [vm-test-run-centjes-e2e-test] client: starting vm
  136. 26.11 s [vm-test-run-centjes-e2e-test] docsserver #
  137. 26.11 s [vm-test-run-centjes-e2e-test] docsserver # Virtualisation disk image created.
  138. 26.13 s [vm-test-run-centjes-e2e-test] mke2fs 1.47.3 (8-Jul-2025)
  139. 26.20 s [vm-test-run-centjes-e2e-test] docsserver # c[?7lSeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)
  140. 26.24 s [vm-test-run-centjes-e2e-test] client: QEMU running (pid 31)
  141. 26.24 s [vm-test-run-centjes-e2e-test] client # Disk image does not exist, creating the virtualisation disk image...
  142. 26.24 s [vm-test-run-centjes-e2e-test] client # Formatting '/build/vm-state-client/tmp.TbPRvkqN9h', fmt=raw size=1073741824
  143. 26.24 s [vm-test-run-centjes-e2e-test] docsserver: waiting for unit default.target
  144. 26.24 s [vm-test-run-centjes-e2e-test] client # Discarding device blocks: 0/262144 done
  145. 26.24 s [vm-test-run-centjes-e2e-test] docsserver: waiting for the VM to finish booting
  146. 26.24 s [vm-test-run-centjes-e2e-test] client # Creating filesystem with 262144 4k blocks and 65536 inodes
  147. 26.24 s [vm-test-run-centjes-e2e-test] client # Filesystem UUID: c00e5177-af61-4791-a5a3-b08482f95c0f
  148. 26.24 s [vm-test-run-centjes-e2e-test] client # Superblock backups stored on blocks:
  149. 26.24 s [vm-test-run-centjes-e2e-test] client # 32768, 98304, 163840, 229376
  150. 26.24 s [vm-test-run-centjes-e2e-test] client #
  151. 26.24 s [vm-test-run-centjes-e2e-test] client # Allocating group tables: 0/8 done
  152. 26.24 s [vm-test-run-centjes-e2e-test] client # Writing inode tables: 0/8 done
  153. 26.24 s [vm-test-run-centjes-e2e-test] client # Creating journal (8192 blocks): done
  154. 26.24 s [vm-test-run-centjes-e2e-test] client # Writing superblocks and filesystem accounting information: 0/8 done
  155. 26.24 s [vm-test-run-centjes-e2e-test] client #
  156. 26.24 s [vm-test-run-centjes-e2e-test] client # Virtualisation disk image created.
  157. 26.30 s [vm-test-run-centjes-e2e-test] docsserver #
  158. 26.30 s [vm-test-run-centjes-e2e-test] docsserver #
  159. 26.31 s [vm-test-run-centjes-e2e-test] docsserver # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+3EFD1920+3EF31920 CA00
  160. 26.32 s [vm-test-run-centjes-e2e-test] docsserver # Press Ctrl-B to configure iPXE (PCI 00:03.0)...
  161. 26.32 s [vm-test-run-centjes-e2e-test] docsserver #
  162. 26.32 s [vm-test-run-centjes-e2e-test] docsserver #
  163. 26.32 s [vm-test-run-centjes-e2e-test] docsserver #
  164. 26.32 s [vm-test-run-centjes-e2e-test] docsserver #
  165. 26.33 s [vm-test-run-centjes-e2e-test] docsserver # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM 3EFD1920 3EF31920 CB00
  166. 26.33 s [vm-test-run-centjes-e2e-test] client # c[?7lSeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)
  167. 26.34 s [vm-test-run-centjes-e2e-test] docsserver # Press Ctrl-B to configure iPXE (PCI 00:09.0)...
  168. 26.34 s [vm-test-run-centjes-e2e-test] docsserver #
  169. 26.35 s [vm-test-run-centjes-e2e-test] docsserver #
  170. 26.35 s [vm-test-run-centjes-e2e-test] docsserver # Booting from ROM...
  171. 26.37 s [vm-test-run-centjes-e2e-test] docsserver # Probing EDD (edd=off to disable)... ok
  172. 26.44 s [vm-test-run-centjes-e2e-test] client #
  173. 26.44 s [vm-test-run-centjes-e2e-test] client #
  174. 26.44 s [vm-test-run-centjes-e2e-test] client # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+3EFD1920+3EF31920 CA00
  175. 26.46 s [vm-test-run-centjes-e2e-test] client # Press Ctrl-B to configure iPXE (PCI 00:03.0)...
  176. 26.46 s [vm-test-run-centjes-e2e-test] client #
  177. 26.46 s [vm-test-run-centjes-e2e-test] client #
  178. 26.46 s [vm-test-run-centjes-e2e-test] client #
  179. 26.46 s [vm-test-run-centjes-e2e-test] client #
  180. 26.46 s [vm-test-run-centjes-e2e-test] client # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM 3EFD1920 3EF31920 CB00
  181. 26.48 s [vm-test-run-centjes-e2e-test] client # Press Ctrl-B to configure iPXE (PCI 00:09.0)...
  182. 26.48 s [vm-test-run-centjes-e2e-test] client #
  183. 26.48 s [vm-test-run-centjes-e2e-test] client #
  184. 26.49 s [vm-test-run-centjes-e2e-test] client # Booting from ROM...
  185. 26.50 s [vm-test-run-centjes-e2e-test] client # Probing EDD (edd=off to disable)... ok
  186. 26.67 s [vm-test-run-centjes-e2e-test] docsserver # c[?7l[ 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
  187. 26.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/b4ylhqbb1k81nwvff9ifybzbhdcjb9l7-nixos-system-docsserver-test/init regInfo=/nix/store/ngsdybz5cfd5mx5iawx565xpmyvxhj1z-closure-info/registration console=ttyS0,115200n8 console=tty0
  188. 26.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-provided physical RAM map:
  189. 26.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
  190. 26.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
  191. 26.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
  192. 26.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffdafff] usable
  193. 26.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x000000003ffdb000-0x000000003fffffff] reserved
  194. 26.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
  195. 26.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
  196. 26.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved
  197. 26.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] NX (Execute Disable) protection: active
  198. 26.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] APIC: Static calls initialized
  199. 26.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] SMBIOS 2.8 present.
  200. 26.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014
  201. 26.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] DMI: Memory slots populated: 1/1
  202. 26.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] Hypervisor detected: KVM
  203. 26.71 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000
  204. 26.71 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
  205. 26.71 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] kvm-clock: using sched offset of 411338543 cycles
  206. 26.71 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000001] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
  207. 26.71 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000004] tsc: Detected 3399.996 MHz processor
  208. 26.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000684] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000
  209. 26.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000887] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs
  210. 26.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000895] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT
  211. 26.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002619] found SMP MP-table at [mem 0x000f5470-0x000f547f]
  212. 26.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002628] Using GB pages for direct mapping
  213. 26.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002702] RAMDISK: [mem 0x3f3cd000-0x3ffcffff]
  214. 26.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002719] ACPI: Early table checksum verification disabled
  215. 26.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002722] ACPI: RSDP 0x00000000000F5290 000014 (v00 BOCHS )
  216. 26.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002724] ACPI: RSDT 0x000000003FFE23D9 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  217. 26.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002728] ACPI: FACP 0x000000003FFE228D 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  218. 26.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002731] ACPI: DSDT 0x000000003FFE0040 00224D (v01 BOCHS BXPC 00000001 BXPC 00000001)
  219. 26.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002733] ACPI: FACS 0x000000003FFE0000 000040
  220. 26.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002734] ACPI: APIC 0x000000003FFE2301 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)
  221. 26.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002736] ACPI: HPET 0x000000003FFE2379 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  222. 26.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002737] ACPI: WAET 0x000000003FFE23B1 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  223. 26.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002738] ACPI: Reserving FACP table memory at [mem 0x3ffe228d-0x3ffe2300]
  224. 26.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002739] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe228c]
  225. 26.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002740] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]
  226. 26.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002740] ACPI: Reserving APIC table memory at [mem 0x3ffe2301-0x3ffe2378]
  227. 26.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002740] ACPI: Reserving HPET table memory at [mem 0x3ffe2379-0x3ffe23b0]
  228. 26.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002741] ACPI: Reserving WAET table memory at [mem 0x3ffe23b1-0x3ffe23d8]
  229. 26.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003496] No NUMA configuration found
  230. 26.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003497] Faking a node at [mem 0x0000000000000000-0x000000003ffdafff]
  231. 26.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003499] NODE_DATA(0) allocated [mem 0x3ffd58c0-0x3ffdadff]
  232. 26.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003513] Zone ranges:
  233. 26.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003514] DMA [mem 0x0000000000001000-0x0000000000ffffff]
  234. 26.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003515] DMA32 [mem 0x0000000001000000-0x000000003ffdafff]
  235. 26.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003515] Normal empty
  236. 26.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003516] Device empty
  237. 26.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003516] Movable zone start for each node
  238. 26.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003517] Early memory node ranges
  239. 26.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003517] node 0: [mem 0x0000000000001000-0x000000000009efff]
  240. 26.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003518] node 0: [mem 0x0000000000100000-0x000000003ffdafff]
  241. 26.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003519] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdafff]
  242. 26.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003642] On node 0, zone DMA: 1 pages in unavailable ranges
  243. 26.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003654] On node 0, zone DMA: 97 pages in unavailable ranges
  244. 26.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.006130] On node 0, zone DMA32: 37 pages in unavailable ranges
  245. 26.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007700] ACPI: PM-Timer IO Port: 0x608
  246. 26.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007723] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
  247. 26.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007805] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
  248. 26.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007806] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
  249. 26.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007808] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
  250. 26.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007808] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
  251. 26.80 s [vm-test-run-centjes-e2e-test] client # c[?7l[ 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
  252. 26.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007809] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
  253. 26.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007810] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
  254. 26.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007812] ACPI: Using ACPI (MADT) for SMP configuration information
  255. 26.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007812] ACPI: HPET id: 0x8086a201 base: 0xfed00000
  256. 26.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007814] TSC deadline timer available
  257. 26.81 s [vm-test-run-centjes-e2e-test] client # [ 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
  258. 26.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007817] CPU topo: Max. logical packages: 1
  259. 26.81 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-provided physical RAM map:
  260. 26.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007818] CPU topo: Max. logical dies: 1
  261. 26.81 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
  262. 26.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007818] CPU topo: Max. dies per package: 1
  263. 26.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007821] CPU topo: Max. threads per core: 1
  264. 26.81 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
  265. 26.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007822] CPU topo: Num. cores per package: 1
  266. 26.82 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
  267. 26.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007822] CPU topo: Num. threads per package: 1
  268. 26.82 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffdafff] usable
  269. 26.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007822] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs
  270. 26.82 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x000000003ffdb000-0x000000003fffffff] reserved
  271. 26.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007848] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()
  272. 26.82 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
  273. 26.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007900] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]
  274. 26.83 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
  275. 26.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007901] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]
  276. 26.83 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved
  277. 26.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007902] [mem 0x40000000-0xfeffbfff] available for PCI devices
  278. 26.83 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] NX (Execute Disable) protection: active
  279. 26.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007903] Booting paravirtualized kernel on KVM
  280. 26.83 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] APIC: Static calls initialized
  281. 26.83 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] SMBIOS 2.8 present.
  282. 26.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007904] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
  283. 26.84 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014
  284. 26.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011171] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1
  285. 26.84 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] DMI: Memory slots populated: 1/1
  286. 26.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011412] percpu: Embedded 116 pages/cpu s294912 r65536 d114688 u2097152
  287. 26.84 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] Hypervisor detected: KVM
  288. 26.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011482] kvm-guest: PV spinlocks disabled, single CPU
  289. 26.84 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000
  290. 26.84 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
  291. 26.84 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] kvm-clock: using sched offset of 414300246 cycles
  292. 26.85 s [vm-test-run-centjes-e2e-test] client # [ 0.000001] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
  293. 26.85 s [vm-test-run-centjes-e2e-test] client # [ 0.000003] tsc: Detected 3399.996 MHz processor
  294. 26.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011483] Kernel command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/b4ylhqbb1k81nwvff9ifybzbhdcjb9l7-nixos-system-docsserver-test/init regInfo=/nix/store/ngsdybz5cfd5mx5iawx565xpmyvxhj1z-closure-info/registration console=ttyS0,115200n8 console=tty0
  295. 26.85 s [vm-test-run-centjes-e2e-test] client # [ 0.000660] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000
  296. 26.85 s [vm-test-run-centjes-e2e-test] client # [ 0.000877] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs
  297. 26.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011550] Unknown kernel command line parameters "regInfo=/nix/store/ngsdybz5cfd5mx5iawx565xpmyvxhj1z-closure-info/registration", will be passed to user space.
  298. 26.86 s [vm-test-run-centjes-e2e-test] client # [ 0.000885] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT
  299. 26.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011564] random: crng init done
  300. 26.86 s [vm-test-run-centjes-e2e-test] client # [ 0.002588] found SMP MP-table at [mem 0x000f5470-0x000f547f]
  301. 26.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011616] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
  302. 26.86 s [vm-test-run-centjes-e2e-test] client # [ 0.002595] Using GB pages for direct mapping
  303. 26.86 s [vm-test-run-centjes-e2e-test] client # [ 0.002724] RAMDISK: [mem 0x3f3cd000-0x3ffcffff]
  304. 26.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011646] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
  305. 26.86 s [vm-test-run-centjes-e2e-test] client # [ 0.002740] ACPI: Early table checksum verification disabled
  306. 26.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011679] Fallback order for Node 0: 0
  307. 26.86 s [vm-test-run-centjes-e2e-test] client # [ 0.002742] ACPI: RSDP 0x00000000000F5290 000014 (v00 BOCHS )
  308. 26.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011681] Built 1 zonelists, mobility grouping on. Total pages: 262009
  309. 26.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011682] Policy zone: DMA32
  310. 26.87 s [vm-test-run-centjes-e2e-test] client # [ 0.002744] ACPI: RSDT 0x000000003FFE23D9 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  311. 26.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011884] mem auto-init: stack:all(zero), heap alloc:on, heap free:off
  312. 26.87 s [vm-test-run-centjes-e2e-test] client # [ 0.002747] ACPI: FACP 0x000000003FFE228D 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  313. 26.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.013564] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
  314. 26.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.013776] allocated 2097152 bytes of page_ext
  315. 26.87 s [vm-test-run-centjes-e2e-test] client # [ 0.002750] ACPI: DSDT 0x000000003FFE0040 00224D (v01 BOCHS BXPC 00000001 BXPC 00000001)
  316. 26.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.013786] ftrace: allocating 46208 entries in 181 pages
  317. 26.87 s [vm-test-run-centjes-e2e-test] client # [ 0.002751] ACPI: FACS 0x000000003FFE0000 000040
  318. 26.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.021198] ftrace: allocated 181 pages with 5 groups
  319. 26.88 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.021689] Dynamic Preempt: voluntary
  320. 26.93 s [vm-test-run-centjes-e2e-test] client # [ 0.002752] ACPI: APIC 0x000000003FFE2301 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)
  321. 26.93 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.021882] rcu: Preemptible hierarchical RCU implementation.
  322. 26.93 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.021883] rcu: RCU event tracing is enabled.
  323. 26.93 s [vm-test-run-centjes-e2e-test] client # [ 0.002754] ACPI: HPET 0x000000003FFE2379 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  324. 26.93 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.021883] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.
  325. 26.93 s [vm-test-run-centjes-e2e-test] client # [ 0.002755] ACPI: WAET 0x000000003FFE23B1 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  326. 26.93 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.021884] Trampoline variant of Tasks RCU enabled.
  327. 26.93 s [vm-test-run-centjes-e2e-test] client # [ 0.002756] ACPI: Reserving FACP table memory at [mem 0x3ffe228d-0x3ffe2300]
  328. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.021884] Rude variant of Tasks RCU enabled.
  329. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.021885] Tracing variant of Tasks RCU enabled.
  330. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.002757] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe228c]
  331. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.021885] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
  332. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.002757] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]
  333. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.021886] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
  334. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.002758] ACPI: Reserving APIC table memory at [mem 0x3ffe2301-0x3ffe2378]
  335. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.002758] ACPI: Reserving HPET table memory at [mem 0x3ffe2379-0x3ffe23b0]
  336. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.021891] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
  337. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.002759] ACPI: Reserving WAET table memory at [mem 0x3ffe23b1-0x3ffe23d8]
  338. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.021893] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
  339. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.003511] No NUMA configuration found
  340. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.003512] Faking a node at [mem 0x0000000000000000-0x000000003ffdafff]
  341. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.021894] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
  342. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.003514] NODE_DATA(0) allocated [mem 0x3ffd58c0-0x3ffdadff]
  343. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.025166] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16
  344. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.003527] Zone ranges:
  345. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.025413] rcu: srcu_init: Setting srcu_struct sizes based on contention.
  346. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.003527] DMA [mem 0x0000000000001000-0x0000000000ffffff]
  347. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.003528] DMA32 [mem 0x0000000001000000-0x000000003ffdafff]
  348. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.003529] Normal empty
  349. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.025474] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)
  350. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.003529] Device empty
  351. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.038909] Console: colour VGA+ 80x25
  352. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.003530] Movable zone start for each node
  353. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.038912] printk: legacy console [tty0] enabled
  354. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.003530] Early memory node ranges
  355. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.141931] printk: legacy console [ttyS0] enabled
  356. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.003531] node 0: [mem 0x0000000000001000-0x000000000009efff]
  357. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.393543] ACPI: Core revision 20240827
  358. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.003532] node 0: [mem 0x0000000000100000-0x000000003ffdafff]
  359. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.003532] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdafff]
  360. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.395778] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
  361. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.003690] On node 0, zone DMA: 1 pages in unavailable ranges
  362. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.400240] APIC: Switch to symmetric I/O mode setup
  363. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.003737] On node 0, zone DMA: 97 pages in unavailable ranges
  364. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.006245] On node 0, zone DMA32: 37 pages in unavailable ranges
  365. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.403507] x2apic enabled
  366. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.007836] ACPI: PM-Timer IO Port: 0x608
  367. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.007859] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
  368. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.405854] APIC: Switched APIC routing to: physical x2apic
  369. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.007942] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
  370. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.007944] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
  371. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.007945] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
  372. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.412288] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
  373. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.007945] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
  374. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.007946] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
  375. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.415170] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns
  376. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.007947] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
  377. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.007949] ACPI: Using ACPI (MADT) for SMP configuration information
  378. 26.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.419970] Calibrating delay loop (skipped) preset value.. 6799.99 BogoMIPS (lpj=3399996)
  379. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.007949] ACPI: HPET id: 0x8086a201 base: 0xfed00000
  380. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.007951] TSC deadline timer available
  381. 26.94 s [vm-test-run-centjes-e2e-test] client # [ 0.007954] CPU topo: Max. logical packages: 1
  382. 26.95 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.423170] x86/cpu: User Mode Instruction Prevention (UMIP) activated
  383. 26.95 s [vm-test-run-centjes-e2e-test] client # [ 0.007955] CPU topo: Max. logical dies: 1
  384. 26.95 s [vm-test-run-centjes-e2e-test] client # [ 0.007955] CPU topo: Max. dies per package: 1
  385. 26.95 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.425394] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127
  386. 26.95 s [vm-test-run-centjes-e2e-test] client # [ 0.007958] CPU topo: Max. threads per core: 1
  387. 26.95 s [vm-test-run-centjes-e2e-test] client # [ 0.007958] CPU topo: Num. cores per package: 1
  388. 26.95 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.426969] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0
  389. 26.95 s [vm-test-run-centjes-e2e-test] client # [ 0.007959] CPU topo: Num. threads per package: 1
  390. 26.95 s [vm-test-run-centjes-e2e-test] client # [ 0.007959] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs
  391. 26.96 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.428979] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
  392. 26.96 s [vm-test-run-centjes-e2e-test] client # [ 0.007984] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()
  393. 26.96 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.430970] Spectre V2 : Mitigation: Retpolines
  394. 26.96 s [vm-test-run-centjes-e2e-test] client # [ 0.008035] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]
  395. 26.96 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.432969] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT
  396. 26.96 s [vm-test-run-centjes-e2e-test] client # [ 0.008036] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]
  397. 26.96 s [vm-test-run-centjes-e2e-test] client # [ 0.008037] [mem 0x40000000-0xfeffbfff] available for PCI devices
  398. 26.96 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.434969] Spectre V2 : Enabling Restricted Speculation for firmware calls
  399. 26.97 s [vm-test-run-centjes-e2e-test] client # [ 0.008038] Booting paravirtualized kernel on KVM
  400. 26.97 s [vm-test-run-centjes-e2e-test] client # [ 0.008039] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
  401. 26.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.436970] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
  402. 26.97 s [vm-test-run-centjes-e2e-test] client # [ 0.011303] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1
  403. 26.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.438970] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl
  404. 26.97 s [vm-test-run-centjes-e2e-test] client # [ 0.011560] percpu: Embedded 116 pages/cpu s294912 r65536 d114688 u2097152
  405. 26.97 s [vm-test-run-centjes-e2e-test] client # [ 0.011629] kvm-guest: PV spinlocks disabled, single CPU
  406. 26.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.440969] active return thunk: srso_alias_return_thunk
  407. 26.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.442969] Speculative Return Stack Overflow: Mitigation: Safe RET
  408. 26.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.444969] Transient Scheduler Attacks: Forcing mitigation on in a VM
  409. 26.98 s [vm-test-run-centjes-e2e-test] client # [ 0.011630] Kernel command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/001k9r4jinw78ih5x5hxggb82ynlbkqn-nixos-system-client-test/init regInfo=/nix/store/j7kn31fka8vp5bhm6sw8hv64iyzix7xw-closure-info/registration console=ttyS0,115200n8 console=tty0
  410. 26.99 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.445969] Transient Scheduler Attacks: Vulnerable: Clear CPU buffers attempted, no microcode
  411. 26.99 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.448052] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
  412. 26.99 s [vm-test-run-centjes-e2e-test] client # [ 0.011697] Unknown kernel command line parameters "regInfo=/nix/store/j7kn31fka8vp5bhm6sw8hv64iyzix7xw-closure-info/registration", will be passed to user space.
  413. 26.99 s [vm-test-run-centjes-e2e-test] client # [ 0.011715] random: crng init done
  414. 26.99 s [vm-test-run-centjes-e2e-test] client # [ 0.011768] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
  415. 27.00 s [vm-test-run-centjes-e2e-test] client # [ 0.011816] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
  416. 27.00 s [vm-test-run-centjes-e2e-test] client # [ 0.011849] Fallback order for Node 0: 0
  417. 27.00 s [vm-test-run-centjes-e2e-test] client # [ 0.011851] Built 1 zonelists, mobility grouping on. Total pages: 262009
  418. 27.00 s [vm-test-run-centjes-e2e-test] client # [ 0.011852] Policy zone: DMA32
  419. 27.00 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.449969] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
  420. 27.00 s [vm-test-run-centjes-e2e-test] client # [ 0.012056] mem auto-init: stack:all(zero), heap alloc:on, heap free:off
  421. 27.00 s [vm-test-run-centjes-e2e-test] client # [ 0.013735] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
  422. 27.00 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.451969] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
  423. 27.01 s [vm-test-run-centjes-e2e-test] client # [ 0.013964] allocated 2097152 bytes of page_ext
  424. 27.01 s [vm-test-run-centjes-e2e-test] client # [ 0.013975] ftrace: allocating 46208 entries in 181 pages
  425. 27.01 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.453969] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'
  426. 27.01 s [vm-test-run-centjes-e2e-test] client # [ 0.021327] ftrace: allocated 181 pages with 5 groups
  427. 27.01 s [vm-test-run-centjes-e2e-test] client # [ 0.021834] Dynamic Preempt: voluntary
  428. 27.01 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.455969] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256
  429. 27.01 s [vm-test-run-centjes-e2e-test] client # [ 0.022028] rcu: Preemptible hierarchical RCU implementation.
  430. 27.01 s [vm-test-run-centjes-e2e-test] client # [ 0.022029] rcu: RCU event tracing is enabled.
  431. 27.01 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.457969] x86/fpu: xstate_offset[9]: 832, xstate_sizes[9]: 8
  432. 27.02 s [vm-test-run-centjes-e2e-test] client # [ 0.022029] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.
  433. 27.02 s [vm-test-run-centjes-e2e-test] client # [ 0.022030] Trampoline variant of Tasks RCU enabled.
  434. 27.02 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.459969] x86/fpu: Enabled xstate features 0x207, context size is 840 bytes, using 'compacted' format.
  435. 27.02 s [vm-test-run-centjes-e2e-test] client # [ 0.022031] Rude variant of Tasks RCU enabled.
  436. 27.02 s [vm-test-run-centjes-e2e-test] client # [ 0.022031] Tracing variant of Tasks RCU enabled.
  437. 27.02 s [vm-test-run-centjes-e2e-test] client # [ 0.022032] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
  438. 27.02 s [vm-test-run-centjes-e2e-test] client # [ 0.022032] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
  439. 27.03 s [vm-test-run-centjes-e2e-test] client # [ 0.022037] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
  440. 27.03 s [vm-test-run-centjes-e2e-test] client # [ 0.022038] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
  441. 27.03 s [vm-test-run-centjes-e2e-test] client # [ 0.022039] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
  442. 27.04 s [vm-test-run-centjes-e2e-test] client # [ 0.025358] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16
  443. 27.04 s [vm-test-run-centjes-e2e-test] client # [ 0.025601] rcu: srcu_init: Setting srcu_struct sizes based on contention.
  444. 27.04 s [vm-test-run-centjes-e2e-test] client # [ 0.025655] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)
  445. 27.04 s [vm-test-run-centjes-e2e-test] client # [ 0.039249] Console: colour VGA+ 80x25
  446. 27.04 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.486029] Freeing SMP alternatives memory: 40K
  447. 27.04 s [vm-test-run-centjes-e2e-test] client # [ 0.039251] printk: legacy console [tty0] enabled
  448. 27.04 s [vm-test-run-centjes-e2e-test] client # [ 0.143459] printk: legacy console [ttyS0] enabled
  449. 27.05 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.486970] pid_max: default: 32768 minimum: 301
  450. 27.05 s [vm-test-run-centjes-e2e-test] client # [ 0.394005] ACPI: Core revision 20240827
  451. 27.05 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.489005] LSM: initializing lsm=capability,landlock,yama,bpf
  452. 27.05 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.491036] landlock: Up and running.
  453. 27.05 s [vm-test-run-centjes-e2e-test] client # [ 0.396198] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
  454. 27.05 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.491969] Yama: becoming mindful.
  455. 27.05 s [vm-test-run-centjes-e2e-test] client # [ 0.400520] APIC: Switch to symmetric I/O mode setup
  456. 27.05 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.494039] LSM support for eBPF active
  457. 27.06 s [vm-test-run-centjes-e2e-test] client # [ 0.403737] x2apic enabled
  458. 27.06 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.494993] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
  459. 27.06 s [vm-test-run-centjes-e2e-test] client # [ 0.406060] APIC: Switched APIC routing to: physical x2apic
  460. 27.06 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.496970] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
  461. 27.07 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.499311] smpboot: CPU0: AMD Ryzen 9 5950X 16-Core Processor (family: 0x19, model: 0x21, stepping: 0x0)
  462. 27.07 s [vm-test-run-centjes-e2e-test] client # [ 0.412410] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
  463. 27.07 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.501218] Performance Events: Fam17h+ core perfctr, AMD PMU driver.
  464. 27.07 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.502984] ... version: 0
  465. 27.07 s [vm-test-run-centjes-e2e-test] client # [ 0.415303] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns
  466. 27.07 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.503977] ... bit width: 48
  467. 27.07 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.504977] ... generic registers: 6
  468. 27.07 s [vm-test-run-centjes-e2e-test] client # [ 0.420108] Calibrating delay loop (skipped) preset value.. 6799.99 BogoMIPS (lpj=3399996)
  469. 27.08 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.505977] ... value mask: 0000ffffffffffff
  470. 27.08 s [vm-test-run-centjes-e2e-test] client # [ 0.423158] x86/cpu: User Mode Instruction Prevention (UMIP) activated
  471. 27.08 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.506977] ... max period: 00007fffffffffff
  472. 27.08 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.507977] ... fixed-purpose events: 0
  473. 27.08 s [vm-test-run-centjes-e2e-test] client # [ 0.424565] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127
  474. 27.08 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.508977] ... event mask: 000000000000003f
  475. 27.08 s [vm-test-run-centjes-e2e-test] client # [ 0.426108] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0
  476. 27.09 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.510047] signal: max sigframe size: 3376
  477. 27.09 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.511005] rcu: Hierarchical SRCU implementation.
  478. 27.09 s [vm-test-run-centjes-e2e-test] client # [ 0.428117] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
  479. 27.09 s [vm-test-run-centjes-e2e-test] client # [ 0.430109] Spectre V2 : Mitigation: Retpolines
  480. 27.09 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.511977] rcu: Max phase no-delay instances is 400.
  481. 27.09 s [vm-test-run-centjes-e2e-test] client # [ 0.431108] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT
  482. 27.10 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.515938] smp: Bringing up secondary CPUs ...
  483. 27.10 s [vm-test-run-centjes-e2e-test] client # [ 0.433108] Spectre V2 : Enabling Restricted Speculation for firmware calls
  484. 27.10 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.516984] smp: Brought up 1 node, 1 CPU
  485. 27.10 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.517978] smpboot: Total of 1 processors activated (6799.99 BogoMIPS)
  486. 27.10 s [vm-test-run-centjes-e2e-test] client # [ 0.435109] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
  487. 27.11 s [vm-test-run-centjes-e2e-test] client # [ 0.437109] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl
  488. 27.11 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.519266] Memory: 962124K/1048036K available (16384K kernel code, 2645K rwdata, 12580K rodata, 3408K init, 3360K bss, 78836K reserved, 0K cma-reserved)
  489. 27.11 s [vm-test-run-centjes-e2e-test] client # [ 0.439108] active return thunk: srso_alias_return_thunk
  490. 27.11 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.521055] devtmpfs: initialized
  491. 27.11 s [vm-test-run-centjes-e2e-test] client # [ 0.440108] Speculative Return Stack Overflow: Mitigation: Safe RET
  492. 27.11 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.522020] x86/mm: Memory block size: 128MB
  493. 27.11 s [vm-test-run-centjes-e2e-test] client # [ 0.442108] Transient Scheduler Attacks: Forcing mitigation on in a VM
  494. 27.12 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.523409] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
  495. 27.12 s [vm-test-run-centjes-e2e-test] client # [ 0.444108] Transient Scheduler Attacks: Vulnerable: Clear CPU buffers attempted, no microcode
  496. 27.12 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.524979] futex hash table entries: 256 (order: 2, 16384 bytes, linear)
  497. 27.12 s [vm-test-run-centjes-e2e-test] client # [ 0.446189] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
  498. 27.12 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.526021] pinctrl core: initialized pinctrl subsystem
  499. 27.12 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.527250] PM: RTC time: 13:54:56, date: 2026-04-27
  500. 27.13 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.528487] NET: Registered PF_NETLINK/PF_ROUTE protocol family
  501. 27.13 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.530116] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations
  502. 27.13 s [vm-test-run-centjes-e2e-test] client # [ 0.448108] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
  503. 27.13 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.531985] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
  504. 27.14 s [vm-test-run-centjes-e2e-test] client # [ 0.450108] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
  505. 27.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.533980] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
  506. 27.14 s [vm-test-run-centjes-e2e-test] client # [ 0.452108] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'
  507. 27.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.535982] audit: initializing netlink subsys (disabled)
  508. 27.14 s [vm-test-run-centjes-e2e-test] client # [ 0.454108] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256
  509. 27.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.537023] audit: type=2000 audit(1777298096.474:1): state=initialized audit_enabled=0 res=1
  510. 27.15 s [vm-test-run-centjes-e2e-test] client # [ 0.456108] x86/fpu: xstate_offset[9]: 832, xstate_sizes[9]: 8
  511. 27.15 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.539073] thermal_sys: Registered thermal governor 'bang_bang'
  512. 27.15 s [vm-test-run-centjes-e2e-test] client # [ 0.458108] x86/fpu: Enabled xstate features 0x207, context size is 840 bytes, using 'compacted' format.
  513. 27.15 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.539075] thermal_sys: Registered thermal governor 'step_wise'
  514. 27.15 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.539977] thermal_sys: Registered thermal governor 'user_space'
  515. 27.15 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.540988] cpuidle: using governor menu
  516. 27.16 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.544687] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
  517. 27.16 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.546358] PCI: Using configuration type 1 for base access
  518. 27.17 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.547976] PCI: Using configuration type 1 for extended access
  519. 27.17 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.549097] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
  520. 27.17 s [vm-test-run-centjes-e2e-test] client # [ 0.483178] Freeing SMP alternatives memory: 40K
  521. 27.18 s [vm-test-run-centjes-e2e-test] client # [ 0.484109] pid_max: default: 32768 minimum: 301
  522. 27.18 s [vm-test-run-centjes-e2e-test] client # [ 0.485145] LSM: initializing lsm=capability,landlock,yama,bpf
  523. 27.18 s [vm-test-run-centjes-e2e-test] client # [ 0.487198] landlock: Up and running.
  524. 27.18 s [vm-test-run-centjes-e2e-test] client # [ 0.488108] Yama: becoming mindful.
  525. 27.19 s [vm-test-run-centjes-e2e-test] client # [ 0.490248] LSM support for eBPF active
  526. 27.19 s [vm-test-run-centjes-e2e-test] client # [ 0.491127] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
  527. 27.19 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.570194] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
  528. 27.19 s [vm-test-run-centjes-e2e-test] client # [ 0.493110] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
  529. 27.19 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.571977] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page
  530. 27.20 s [vm-test-run-centjes-e2e-test] client # [ 0.495480] smpboot: CPU0: AMD Ryzen 9 5950X 16-Core Processor (family: 0x19, model: 0x21, stepping: 0x0)
  531. 27.20 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.572977] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
  532. 27.20 s [vm-test-run-centjes-e2e-test] client # [ 0.497322] Performance Events: Fam17h+ core perfctr, AMD PMU driver.
  533. 27.20 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.573976] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
  534. 27.20 s [vm-test-run-centjes-e2e-test] client # [ 0.499123] ... version: 0
  535. 27.20 s [vm-test-run-centjes-e2e-test] client # [ 0.500116] ... bit width: 48
  536. 27.21 s [vm-test-run-centjes-e2e-test] client # [ 0.501116] ... generic registers: 6
  537. 27.21 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.579578] ACPI: Added _OSI(Module Device)
  538. 27.21 s [vm-test-run-centjes-e2e-test] client # [ 0.502116] ... value mask: 0000ffffffffffff
  539. 27.21 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.580977] ACPI: Added _OSI(Processor Device)
  540. 27.21 s [vm-test-run-centjes-e2e-test] client # [ 0.503116] ... max period: 00007fffffffffff
  541. 27.21 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.581977] ACPI: Added _OSI(Processor Aggregator Device)
  542. 27.21 s [vm-test-run-centjes-e2e-test] client # [ 0.504116] ... fixed-purpose events: 0
  543. 27.22 s [vm-test-run-centjes-e2e-test] client # [ 0.505116] ... event mask: 000000000000003f
  544. 27.22 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.584535] ACPI: 1 ACPI AML tables successfully acquired and loaded
  545. 27.22 s [vm-test-run-centjes-e2e-test] client # [ 0.506187] signal: max sigframe size: 3376
  546. 27.22 s [vm-test-run-centjes-e2e-test] client # [ 0.507145] rcu: Hierarchical SRCU implementation.
  547. 27.22 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.587178] ACPI: Interpreter enabled
  548. 27.22 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.587989] ACPI: PM: (supports S0 S3 S4 S5)
  549. 27.22 s [vm-test-run-centjes-e2e-test] client # [ 0.508117] rcu: Max phase no-delay instances is 400.
  550. 27.22 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.588977] ACPI: Using IOAPIC for interrupt routing
  551. 27.23 s [vm-test-run-centjes-e2e-test] client # [ 0.512029] smp: Bringing up secondary CPUs ...
  552. 27.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.589988] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
  553. 27.23 s [vm-test-run-centjes-e2e-test] client # [ 0.513123] smp: Brought up 1 node, 1 CPU
  554. 27.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.591976] PCI: Using E820 reservations for host bridge windows
  555. 27.23 s [vm-test-run-centjes-e2e-test] client # [ 0.514118] smpboot: Total of 1 processors activated (6799.99 BogoMIPS)
  556. 27.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.593118] ACPI: Enabled 2 GPEs in block 00 to 0F
  557. 27.24 s [vm-test-run-centjes-e2e-test] client # [ 0.515411] Memory: 962140K/1048036K available (16384K kernel code, 2645K rwdata, 12580K rodata, 3408K init, 3360K bss, 78836K reserved, 0K cma-reserved)
  558. 27.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.597465] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
  559. 27.24 s [vm-test-run-centjes-e2e-test] client # [ 0.517192] devtmpfs: initialized
  560. 27.24 s [vm-test-run-centjes-e2e-test] client # [ 0.518160] x86/mm: Memory block size: 128MB
  561. 27.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.598980] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]
  562. 27.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.601268] acpiphp: Slot [3] registered
  563. 27.25 s [vm-test-run-centjes-e2e-test] client # [ 0.519528] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
  564. 27.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.602012] acpiphp: Slot [4] registered
  565. 27.25 s [vm-test-run-centjes-e2e-test] client # [ 0.521118] futex hash table entries: 256 (order: 2, 16384 bytes, linear)
  566. 27.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.603014] acpiphp: Slot [5] registered
  567. 27.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.604032] acpiphp: Slot [6] registered
  568. 27.25 s [vm-test-run-centjes-e2e-test] client # [ 0.522161] pinctrl core: initialized pinctrl subsystem
  569. 27.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.605012] acpiphp: Slot [7] registered
  570. 27.26 s [vm-test-run-centjes-e2e-test] client # [ 0.523413] PM: RTC time: 13:54:57, date: 2026-04-27
  571. 27.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.606011] acpiphp: Slot [8] registered
  572. 27.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.607013] acpiphp: Slot [9] registered
  573. 27.26 s [vm-test-run-centjes-e2e-test] client # [ 0.524613] NET: Registered PF_NETLINK/PF_ROUTE protocol family
  574. 27.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.608011] acpiphp: Slot [10] registered
  575. 27.26 s [vm-test-run-centjes-e2e-test] client # [ 0.526239] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations
  576. 27.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.609012] acpiphp: Slot [11] registered
  577. 27.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.610012] acpiphp: Slot [12] registered
  578. 27.27 s [vm-test-run-centjes-e2e-test] client # [ 0.528124] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
  579. 27.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.611014] acpiphp: Slot [13] registered
  580. 27.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.612022] acpiphp: Slot [14] registered
  581. 27.27 s [vm-test-run-centjes-e2e-test] client # [ 0.530120] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
  582. 27.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.613011] acpiphp: Slot [15] registered
  583. 27.27 s [vm-test-run-centjes-e2e-test] client # [ 0.532122] audit: initializing netlink subsys (disabled)
  584. 27.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.614013] acpiphp: Slot [16] registered
  585. 27.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.615011] acpiphp: Slot [17] registered
  586. 27.28 s [vm-test-run-centjes-e2e-test] client # [ 0.533142] audit: type=2000 audit(1777298096.603:1): state=initialized audit_enabled=0 res=1
  587. 27.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.616014] acpiphp: Slot [18] registered
  588. 27.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.617027] acpiphp: Slot [19] registered
  589. 27.28 s [vm-test-run-centjes-e2e-test] client # [ 0.535212] thermal_sys: Registered thermal governor 'bang_bang'
  590. 27.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.618012] acpiphp: Slot [20] registered
  591. 27.28 s [vm-test-run-centjes-e2e-test] client # [ 0.535213] thermal_sys: Registered thermal governor 'step_wise'
  592. 27.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.619012] acpiphp: Slot [21] registered
  593. 27.28 s [vm-test-run-centjes-e2e-test] client # [ 0.536117] thermal_sys: Registered thermal governor 'user_space'
  594. 27.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.620015] acpiphp: Slot [22] registered
  595. 27.29 s [vm-test-run-centjes-e2e-test] client # [ 0.537126] cpuidle: using governor menu
  596. 27.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.621012] acpiphp: Slot [23] registered
  597. 27.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.622013] acpiphp: Slot [24] registered
  598. 27.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.623011] acpiphp: Slot [25] registered
  599. 27.29 s [vm-test-run-centjes-e2e-test] client # [ 0.540871] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
  600. 27.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.624011] acpiphp: Slot [26] registered
  601. 27.29 s [vm-test-run-centjes-e2e-test] client # [ 0.542336] PCI: Using configuration type 1 for base access
  602. 27.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.625011] acpiphp: Slot [27] registered
  603. 27.30 s [vm-test-run-centjes-e2e-test] client # [ 0.543116] PCI: Using configuration type 1 for extended access
  604. 27.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.626013] acpiphp: Slot [28] registered
  605. 27.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.627012] acpiphp: Slot [29] registered
  606. 27.30 s [vm-test-run-centjes-e2e-test] client # [ 0.544237] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
  607. 27.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.628012] acpiphp: Slot [30] registered
  608. 27.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.629013] acpiphp: Slot [31] registered
  609. 27.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.630002] PCI host bridge to bus 0000:00
  610. 27.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.630981] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
  611. 27.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.631977] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
  612. 27.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.632977] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
  613. 27.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.634977] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
  614. 27.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.636977] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
  615. 27.32 s [vm-test-run-centjes-e2e-test] client # [ 0.565262] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
  616. 27.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.638977] pci_bus 0000:00: root bus resource [bus 00-ff]
  617. 27.32 s [vm-test-run-centjes-e2e-test] client # [ 0.567133] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page
  618. 27.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.640420] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint
  619. 27.33 s [vm-test-run-centjes-e2e-test] client # [ 0.568116] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
  620. 27.33 s [vm-test-run-centjes-e2e-test] client # [ 0.569132] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
  621. 27.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.643064] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint
  622. 27.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.646506] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint
  623. 27.34 s [vm-test-run-centjes-e2e-test] client # [ 0.574241] ACPI: Added _OSI(Module Device)
  624. 27.34 s [vm-test-run-centjes-e2e-test] client # [ 0.575116] ACPI: Added _OSI(Processor Device)
  625. 27.34 s [vm-test-run-centjes-e2e-test] client # [ 0.576117] ACPI: Added _OSI(Processor Aggregator Device)
  626. 27.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.651418] pci 0000:00:01.1: BAR 4 [io 0xc1e0-0xc1ef]
  627. 27.35 s [vm-test-run-centjes-e2e-test] client # [ 0.578682] ACPI: 1 ACPI AML tables successfully acquired and loaded
  628. 27.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.654472] pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk
  629. 27.35 s [vm-test-run-centjes-e2e-test] client # [ 0.581316] ACPI: Interpreter enabled
  630. 27.35 s [vm-test-run-centjes-e2e-test] client # [ 0.582129] ACPI: PM: (supports S0 S3 S4 S5)
  631. 27.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.655977] pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk
  632. 27.35 s [vm-test-run-centjes-e2e-test] client # [ 0.583116] ACPI: Using IOAPIC for interrupt routing
  633. 27.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.656977] pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk
  634. 27.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.657977] pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk
  635. 27.36 s [vm-test-run-centjes-e2e-test] client # [ 0.584127] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
  636. 27.36 s [vm-test-run-centjes-e2e-test] client # [ 0.586116] PCI: Using E820 reservations for host bridge windows
  637. 27.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.659314] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint
  638. 27.36 s [vm-test-run-centjes-e2e-test] client # [ 0.587263] ACPI: Enabled 2 GPEs in block 00 to 0F
  639. 27.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.663533] pci 0000:00:01.2: BAR 4 [io 0xc100-0xc11f]
  640. 27.37 s [vm-test-run-centjes-e2e-test] client # [ 0.591692] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
  641. 27.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.667523] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint
  642. 27.37 s [vm-test-run-centjes-e2e-test] client # [ 0.593121] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]
  643. 27.38 s [vm-test-run-centjes-e2e-test] client # [ 0.595452] acpiphp: Slot [3] registered
  644. 27.38 s [vm-test-run-centjes-e2e-test] client # [ 0.596154] acpiphp: Slot [4] registered
  645. 27.38 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.670282] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI
  646. 27.38 s [vm-test-run-centjes-e2e-test] client # [ 0.597155] acpiphp: Slot [5] registered
  647. 27.38 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.672158] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB
  648. 27.38 s [vm-test-run-centjes-e2e-test] client # [ 0.598154] acpiphp: Slot [6] registered
  649. 27.38 s [vm-test-run-centjes-e2e-test] client # [ 0.599152] acpiphp: Slot [7] registered
  650. 27.39 s [vm-test-run-centjes-e2e-test] client # [ 0.600152] acpiphp: Slot [8] registered
  651. 27.39 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.674525] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint
  652. 27.39 s [vm-test-run-centjes-e2e-test] client # [ 0.601153] acpiphp: Slot [9] registered
  653. 27.39 s [vm-test-run-centjes-e2e-test] client # [ 0.602152] acpiphp: Slot [10] registered
  654. 27.39 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.677594] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]
  655. 27.39 s [vm-test-run-centjes-e2e-test] client # [ 0.603173] acpiphp: Slot [11] registered
  656. 27.39 s [vm-test-run-centjes-e2e-test] client # [ 0.604187] acpiphp: Slot [12] registered
  657. 27.40 s [vm-test-run-centjes-e2e-test] client # [ 0.605155] acpiphp: Slot [13] registered
  658. 27.40 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.681019] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]
  659. 27.40 s [vm-test-run-centjes-e2e-test] client # [ 0.606153] acpiphp: Slot [14] registered
  660. 27.40 s [vm-test-run-centjes-e2e-test] client # [ 0.607151] acpiphp: Slot [15] registered
  661. 27.40 s [vm-test-run-centjes-e2e-test] client # [ 0.608153] acpiphp: Slot [16] registered
  662. 27.40 s [vm-test-run-centjes-e2e-test] client # [ 0.609151] acpiphp: Slot [17] registered
  663. 27.41 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.686599] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref]
  664. 27.41 s [vm-test-run-centjes-e2e-test] client # [ 0.610151] acpiphp: Slot [18] registered
  665. 27.41 s [vm-test-run-centjes-e2e-test] client # [ 0.611153] acpiphp: Slot [19] registered
  666. 27.41 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.688122] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
  667. 27.41 s [vm-test-run-centjes-e2e-test] client # [ 0.612155] acpiphp: Slot [20] registered
  668. 27.41 s [vm-test-run-centjes-e2e-test] client # [ 0.613154] acpiphp: Slot [21] registered
  669. 27.41 s [vm-test-run-centjes-e2e-test] client # [ 0.614153] acpiphp: Slot [22] registered
  670. 27.41 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.690998] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
  671. 27.42 s [vm-test-run-centjes-e2e-test] client # [ 0.615153] acpiphp: Slot [23] registered
  672. 27.42 s [vm-test-run-centjes-e2e-test] client # [ 0.616154] acpiphp: Slot [24] registered
  673. 27.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.693977] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]
  674. 27.42 s [vm-test-run-centjes-e2e-test] client # [ 0.617153] acpiphp: Slot [25] registered
  675. 27.42 s [vm-test-run-centjes-e2e-test] client # [ 0.618153] acpiphp: Slot [26] registered
  676. 27.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.695977] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]
  677. 27.42 s [vm-test-run-centjes-e2e-test] client # [ 0.619151] acpiphp: Slot [27] registered
  678. 27.43 s [vm-test-run-centjes-e2e-test] client # [ 0.620153] acpiphp: Slot [28] registered
  679. 27.43 s [vm-test-run-centjes-e2e-test] client # [ 0.621153] acpiphp: Slot [29] registered
  680. 27.43 s [vm-test-run-centjes-e2e-test] client # [ 0.622153] acpiphp: Slot [30] registered
  681. 27.43 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.699977] pci 0000:00:03.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]
  682. 27.43 s [vm-test-run-centjes-e2e-test] client # [ 0.623156] acpiphp: Slot [31] registered
  683. 27.43 s [vm-test-run-centjes-e2e-test] client # [ 0.624144] PCI host bridge to bus 0000:00
  684. 27.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.703505] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]
  685. 27.44 s [vm-test-run-centjes-e2e-test] client # [ 0.625120] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
  686. 27.44 s [vm-test-run-centjes-e2e-test] client # [ 0.626117] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
  687. 27.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.707523] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
  688. 27.44 s [vm-test-run-centjes-e2e-test] client # [ 0.627117] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
  689. 27.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.709977] pci 0000:00:04.0: BAR 0 [io 0xc140-0xc15f]
  690. 27.45 s [vm-test-run-centjes-e2e-test] client # [ 0.629117] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
  691. 27.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.711977] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]
  692. 27.45 s [vm-test-run-centjes-e2e-test] client # [ 0.631117] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
  693. 27.45 s [vm-test-run-centjes-e2e-test] client # [ 0.633117] pci_bus 0000:00: root bus resource [bus 00-ff]
  694. 27.46 s [vm-test-run-centjes-e2e-test] client # [ 0.634615] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint
  695. 27.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.716977] pci 0000:00:04.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]
  696. 27.46 s [vm-test-run-centjes-e2e-test] client # [ 0.637416] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint
  697. 27.47 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.721602] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
  698. 27.47 s [vm-test-run-centjes-e2e-test] client # [ 0.640660] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint
  699. 27.47 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.723977] pci 0000:00:05.0: BAR 0 [io 0xc080-0xc0bf]
  700. 27.47 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.725977] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]
  701. 27.48 s [vm-test-run-centjes-e2e-test] client # [ 0.646120] pci 0000:00:01.1: BAR 4 [io 0xc1e0-0xc1ef]
  702. 27.48 s [vm-test-run-centjes-e2e-test] client # [ 0.648842] pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk
  703. 27.48 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.730562] pci 0000:00:05.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]
  704. 27.48 s [vm-test-run-centjes-e2e-test] client # [ 0.650117] pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk
  705. 27.49 s [vm-test-run-centjes-e2e-test] client # [ 0.651117] pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk
  706. 27.49 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.735580] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
  707. 27.49 s [vm-test-run-centjes-e2e-test] client # [ 0.652116] pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk
  708. 27.49 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.737977] pci 0000:00:06.0: BAR 0 [io 0xc160-0xc17f]
  709. 27.49 s [vm-test-run-centjes-e2e-test] client # [ 0.653493] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint
  710. 27.50 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.739978] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]
  711. 27.50 s [vm-test-run-centjes-e2e-test] client # [ 0.658075] pci 0000:00:01.2: BAR 4 [io 0xc100-0xc11f]
  712. 27.50 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.744507] pci 0000:00:06.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]
  713. 27.51 s [vm-test-run-centjes-e2e-test] client # [ 0.661926] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint
  714. 27.51 s [vm-test-run-centjes-e2e-test] client # [ 0.664419] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI
  715. 27.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.749497] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
  716. 27.51 s [vm-test-run-centjes-e2e-test] client # [ 0.666140] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB
  717. 27.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.751977] pci 0000:00:07.0: BAR 0 [io 0xc180-0xc19f]
  718. 27.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.753875] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]
  719. 27.52 s [vm-test-run-centjes-e2e-test] client # [ 0.667675] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint
  720. 27.52 s [vm-test-run-centjes-e2e-test] client # [ 0.670703] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]
  721. 27.53 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.758385] pci 0000:00:07.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]
  722. 27.53 s [vm-test-run-centjes-e2e-test] client # [ 0.674149] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]
  723. 27.53 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.763465] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint
  724. 27.54 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.765977] pci 0000:00:08.0: BAR 0 [io 0xc000-0xc07f]
  725. 27.54 s [vm-test-run-centjes-e2e-test] client # [ 0.680005] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref]
  726. 27.54 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.767977] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]
  727. 27.54 s [vm-test-run-centjes-e2e-test] client # [ 0.681275] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
  728. 27.55 s [vm-test-run-centjes-e2e-test] client # [ 0.683858] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
  729. 27.55 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.771977] pci 0000:00:08.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]
  730. 27.55 s [vm-test-run-centjes-e2e-test] client # [ 0.686116] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]
  731. 27.56 s [vm-test-run-centjes-e2e-test] client # [ 0.688091] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]
  732. 27.56 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.776617] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
  733. 27.56 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.778977] pci 0000:00:09.0: BAR 0 [io 0xc1a0-0xc1bf]
  734. 27.56 s [vm-test-run-centjes-e2e-test] client # [ 0.693116] pci 0000:00:03.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]
  735. 27.57 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.780896] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]
  736. 27.57 s [vm-test-run-centjes-e2e-test] client # [ 0.694967] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]
  737. 27.57 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.784554] pci 0000:00:09.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]
  738. 27.58 s [vm-test-run-centjes-e2e-test] client # [ 0.698720] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
  739. 27.58 s [vm-test-run-centjes-e2e-test] client # [ 0.701116] pci 0000:00:04.0: BAR 0 [io 0xc140-0xc15f]
  740. 27.58 s [vm-test-run-centjes-e2e-test] client # [ 0.703116] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]
  741. 27.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.786955] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref]
  742. 27.59 s [vm-test-run-centjes-e2e-test] client # [ 0.707075] pci 0000:00:04.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]
  743. 27.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.790604] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint
  744. 27.60 s [vm-test-run-centjes-e2e-test] client # [ 0.712086] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
  745. 27.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.793358] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]
  746. 27.60 s [vm-test-run-centjes-e2e-test] client # [ 0.714116] pci 0000:00:05.0: BAR 0 [io 0xc080-0xc0bf]
  747. 27.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.796977] pci 0000:00:0a.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]
  748. 27.61 s [vm-test-run-centjes-e2e-test] client # [ 0.716004] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]
  749. 27.61 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.801515] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint
  750. 27.61 s [vm-test-run-centjes-e2e-test] client # [ 0.719988] pci 0000:00:05.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]
  751. 27.61 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.803977] pci 0000:00:0b.0: BAR 0 [io 0xc0c0-0xc0ff]
  752. 27.62 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.805947] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]
  753. 27.62 s [vm-test-run-centjes-e2e-test] client # [ 0.724877] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
  754. 27.62 s [vm-test-run-centjes-e2e-test] client # [ 0.727116] pci 0000:00:06.0: BAR 0 [io 0xc160-0xc17f]
  755. 27.62 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.809555] pci 0000:00:0b.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]
  756. 27.63 s [vm-test-run-centjes-e2e-test] client # [ 0.728969] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]
  757. 27.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.814732] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
  758. 27.63 s [vm-test-run-centjes-e2e-test] client # [ 0.733117] pci 0000:00:06.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]
  759. 27.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.816977] pci 0000:00:0c.0: BAR 0 [io 0xc1c0-0xc1df]
  760. 27.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.818858] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff]
  761. 27.64 s [vm-test-run-centjes-e2e-test] client # [ 0.738409] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
  762. 27.65 s [vm-test-run-centjes-e2e-test] client # [ 0.741116] pci 0000:00:07.0: BAR 0 [io 0xc180-0xc19f]
  763. 27.65 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.822501] pci 0000:00:0c.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]
  764. 27.65 s [vm-test-run-centjes-e2e-test] client # [ 0.743678] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]
  765. 27.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.828640] ACPI: PCI: Interrupt link LNKA configured for IRQ 10
  766. 27.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.830175] ACPI: PCI: Interrupt link LNKB configured for IRQ 10
  767. 27.66 s [vm-test-run-centjes-e2e-test] client # [ 0.747716] pci 0000:00:07.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]
  768. 27.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.832168] ACPI: PCI: Interrupt link LNKC configured for IRQ 11
  769. 27.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.834157] ACPI: PCI: Interrupt link LNKD configured for IRQ 11
  770. 27.67 s [vm-test-run-centjes-e2e-test] client # [ 0.753159] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint
  771. 27.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.836078] ACPI: PCI: Interrupt link LNKS configured for IRQ 9
  772. 27.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.837324] iommu: Default domain type: Translated
  773. 27.67 s [vm-test-run-centjes-e2e-test] client # [ 0.756116] pci 0000:00:08.0: BAR 0 [io 0xc000-0xc07f]
  774. 27.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.838977] iommu: DMA domain TLB invalidation policy: lazy mode
  775. 27.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.840001] ACPI: bus type USB registered
  776. 27.67 s [vm-test-run-centjes-e2e-test] client # [ 0.758116] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]
  777. 27.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.840998] usbcore: registered new interface driver usbfs
  778. 27.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.841986] usbcore: registered new interface driver hub
  779. 27.68 s [vm-test-run-centjes-e2e-test] client # [ 0.762116] pci 0000:00:08.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]
  780. 27.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.842986] usbcore: registered new device driver usb
  781. 27.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.844263] NetLabel: Initializing
  782. 27.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.844977] NetLabel: domain hash size = 128
  783. 27.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.845977] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO
  784. 27.69 s [vm-test-run-centjes-e2e-test] client # [ 0.766797] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
  785. 27.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.846994] NetLabel: unlabeled traffic allowed by default
  786. 27.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.847977] PCI: Using ACPI for IRQ routing
  787. 27.69 s [vm-test-run-centjes-e2e-test] client # [ 0.769667] pci 0000:00:09.0: BAR 0 [io 0xc1a0-0xc1bf]
  788. 27.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.849726] pci 0000:00:02.0: vgaarb: setting as boot VGA device
  789. 27.70 s [vm-test-run-centjes-e2e-test] client # [ 0.772117] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]
  790. 27.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.849967] pci 0000:00:02.0: vgaarb: bridge control possible
  791. 27.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.849967] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
  792. 27.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.849979] vgaarb: loaded
  793. 27.71 s [vm-test-run-centjes-e2e-test] client # [ 0.776637] pci 0000:00:09.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]
  794. 27.71 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.851047] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
  795. 27.71 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.851977] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
  796. 27.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.857002] clocksource: Switched to clocksource kvm-clock
  797. 27.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.860346] VFS: Disk quotas dquot_6.6.0
  798. 27.72 s [vm-test-run-centjes-e2e-test] client # [ 0.779117] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref]
  799. 27.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.862271] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
  800. 27.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.865470] pnp: PnP ACPI init
  801. 27.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.867647] pnp: PnP ACPI: found 6 devices
  802. 27.73 s [vm-test-run-centjes-e2e-test] client # [ 0.783735] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint
  803. 27.73 s [vm-test-run-centjes-e2e-test] client # [ 0.786532] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]
  804. 27.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.875658] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
  805. 27.74 s [vm-test-run-centjes-e2e-test] client # [ 0.790562] pci 0000:00:0a.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]
  806. 27.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.879720] clocksource: Switched to clocksource acpi_pm
  807. 27.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.882292] NET: Registered PF_INET protocol family
  808. 27.75 s [vm-test-run-centjes-e2e-test] client # [ 0.795279] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint
  809. 27.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.884820] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
  810. 27.75 s [vm-test-run-centjes-e2e-test] client # [ 0.798116] pci 0000:00:0b.0: BAR 0 [io 0xc0c0-0xc0ff]
  811. 27.75 s [vm-test-run-centjes-e2e-test] client # [ 0.800009] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]
  812. 27.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.897700] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
  813. 27.76 s [vm-test-run-centjes-e2e-test] client # [ 0.803992] pci 0000:00:0b.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]
  814. 27.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.901630] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
  815. 27.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.905263] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
  816. 27.77 s [vm-test-run-centjes-e2e-test] client # [ 0.808553] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
  817. 27.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.908957] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)
  818. 27.77 s [vm-test-run-centjes-e2e-test] client # [ 0.811116] pci 0000:00:0c.0: BAR 0 [io 0xc1c0-0xc1df]
  819. 27.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.912411] TCP: Hash tables configured (established 8192 bind 8192)
  820. 27.77 s [vm-test-run-centjes-e2e-test] client # [ 0.813028] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff]
  821. 27.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.915497] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)
  822. 27.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.919082] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
  823. 27.78 s [vm-test-run-centjes-e2e-test] client # [ 0.817004] pci 0000:00:0c.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]
  824. 27.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.922251] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
  825. 27.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.925674] NET: Registered PF_UNIX/PF_LOCAL protocol family
  826. 27.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.928358] NET: Registered PF_XDP protocol family
  827. 27.79 s [vm-test-run-centjes-e2e-test] client # [ 0.821885] ACPI: PCI: Interrupt link LNKA configured for IRQ 10
  828. 27.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.930830] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]
  829. 27.79 s [vm-test-run-centjes-e2e-test] client # [ 0.823343] ACPI: PCI: Interrupt link LNKB configured for IRQ 10
  830. 27.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.933816] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]
  831. 27.79 s [vm-test-run-centjes-e2e-test] client # [ 0.825349] ACPI: PCI: Interrupt link LNKC configured for IRQ 11
  832. 27.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.936824] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
  833. 27.80 s [vm-test-run-centjes-e2e-test] client # [ 0.827343] ACPI: PCI: Interrupt link LNKD configured for IRQ 11
  834. 27.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.940065] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window]
  835. 27.80 s [vm-test-run-centjes-e2e-test] client # [ 0.829251] ACPI: PCI: Interrupt link LNKS configured for IRQ 9
  836. 27.80 s [vm-test-run-centjes-e2e-test] client # [ 0.830506] iommu: Default domain type: Translated
  837. 27.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.943292] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
  838. 27.81 s [vm-test-run-centjes-e2e-test] client # [ 0.832116] iommu: DMA domain TLB invalidation policy: lazy mode
  839. 27.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.946714] pci 0000:00:01.0: PIIX3: Enabling Passive Release
  840. 27.81 s [vm-test-run-centjes-e2e-test] client # [ 0.833142] ACPI: bus type USB registered
  841. 27.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.949461] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
  842. 27.81 s [vm-test-run-centjes-e2e-test] client # [ 0.834137] usbcore: registered new interface driver usbfs
  843. 27.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.953727] ACPI: \_SB_.LNKD: Enabled at IRQ 11
  844. 27.81 s [vm-test-run-centjes-e2e-test] client # [ 0.835140] usbcore: registered new interface driver hub
  845. 27.82 s [vm-test-run-centjes-e2e-test] client # [ 0.836126] usbcore: registered new device driver usb
  846. 27.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.957970] PCI: CLS 0 bytes, default 64
  847. 27.82 s [vm-test-run-centjes-e2e-test] client # [ 0.837441] NetLabel: Initializing
  848. 27.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.960156] Trying to unpack rootfs image as initramfs...
  849. 27.82 s [vm-test-run-centjes-e2e-test] client # [ 0.838116] NetLabel: domain hash size = 128
  850. 27.82 s [vm-test-run-centjes-e2e-test] client # [ 0.839117] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO
  851. 27.83 s [vm-test-run-centjes-e2e-test] client # [ 0.840149] NetLabel: unlabeled traffic allowed by default
  852. 27.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.964683] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns
  853. 27.83 s [vm-test-run-centjes-e2e-test] client # [ 0.841117] PCI: Using ACPI for IRQ routing
  854. 27.83 s [vm-test-run-centjes-e2e-test] client # [ 0.842918] pci 0000:00:02.0: vgaarb: setting as boot VGA device
  855. 27.83 s [vm-test-run-centjes-e2e-test] client # [ 0.843106] pci 0000:00:02.0: vgaarb: bridge control possible
  856. 27.84 s [vm-test-run-centjes-e2e-test] client # [ 0.843106] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
  857. 27.84 s [vm-test-run-centjes-e2e-test] client # [ 0.843119] vgaarb: loaded
  858. 27.84 s [vm-test-run-centjes-e2e-test] client # [ 0.844189] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
  859. 27.85 s [vm-test-run-centjes-e2e-test] client # [ 0.845116] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
  860. 27.85 s [vm-test-run-centjes-e2e-test] client # [ 0.849161] clocksource: Switched to clocksource kvm-clock
  861. 27.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.994453] Initialise system trusted keyrings
  862. 27.85 s [vm-test-run-centjes-e2e-test] client # [ 0.853333] VFS: Disk quotas dquot_6.6.0
  863. 27.86 s [vm-test-run-centjes-e2e-test] client # [ 0.855276] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
  864. 27.86 s [vm-test-run-centjes-e2e-test] client # [ 0.858487] pnp: PnP ACPI init
  865. 27.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.999688] workingset: timestamp_bits=40 max_order=18 bucket_order=0
  866. 27.86 s [vm-test-run-centjes-e2e-test] client # [ 0.860595] pnp: PnP ACPI: found 6 devices
  867. 27.87 s [vm-test-run-centjes-e2e-test] client # [ 0.868462] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
  868. 27.87 s [vm-test-run-centjes-e2e-test] client # [ 0.872455] clocksource: Switched to clocksource acpi_pm
  869. 27.88 s [vm-test-run-centjes-e2e-test] client # [ 0.875068] NET: Registered PF_INET protocol family
  870. 27.88 s [vm-test-run-centjes-e2e-test] client # [ 0.877750] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
  871. 27.89 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.025693] Key type asymmetric registered
  872. 27.89 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.030647] Asymmetric key parser 'x509' registered
  873. 27.89 s [vm-test-run-centjes-e2e-test] client # [ 0.889787] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
  874. 27.90 s [vm-test-run-centjes-e2e-test] client # [ 0.893663] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
  875. 27.90 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.037664] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
  876. 27.90 s [vm-test-run-centjes-e2e-test] client # [ 0.897260] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
  877. 27.90 s [vm-test-run-centjes-e2e-test] client # [ 0.900875] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)
  878. 27.91 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.046104] Freeing initrd memory: 12300K
  879. 27.91 s [vm-test-run-centjes-e2e-test] client # [ 0.904249] TCP: Hash tables configured (established 8192 bind 8192)
  880. 27.91 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.048267] io scheduler mq-deadline registered
  881. 27.91 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.050517] io scheduler kyber registered
  882. 27.91 s [vm-test-run-centjes-e2e-test] client # [ 0.907261] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)
  883. 27.91 s [vm-test-run-centjes-e2e-test] client # [ 0.910771] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
  884. 27.91 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.052971] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
  885. 27.92 s [vm-test-run-centjes-e2e-test] client # [ 0.913829] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
  886. 27.92 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.056337] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
  887. 27.92 s [vm-test-run-centjes-e2e-test] client # [ 0.917072] NET: Registered PF_UNIX/PF_LOCAL protocol family
  888. 27.92 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.060990] Linux agpgart interface v0.103
  889. 27.92 s [vm-test-run-centjes-e2e-test] client # [ 0.919827] NET: Registered PF_XDP protocol family
  890. 27.92 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.063103] ACPI: bus type drm_connector registered
  891. 27.92 s [vm-test-run-centjes-e2e-test] client # [ 0.922199] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]
  892. 27.93 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.065833] usbcore: registered new interface driver usbserial_generic
  893. 27.93 s [vm-test-run-centjes-e2e-test] client # [ 0.925073] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]
  894. 27.93 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.068948] usbserial: USB Serial support registered for generic
  895. 27.93 s [vm-test-run-centjes-e2e-test] client # [ 0.927967] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
  896. 27.93 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.071809] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled
  897. 27.93 s [vm-test-run-centjes-e2e-test] client # [ 0.931194] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window]
  898. 27.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.075319] drop_monitor: Initializing network drop monitor service
  899. 27.94 s [vm-test-run-centjes-e2e-test] client # [ 0.934400] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
  900. 27.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.078396] NET: Registered PF_INET6 protocol family
  901. 27.94 s [vm-test-run-centjes-e2e-test] client # [ 0.937756] pci 0000:00:01.0: PIIX3: Enabling Passive Release
  902. 27.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.081389] Segment Routing with IPv6
  903. 27.94 s [vm-test-run-centjes-e2e-test] client # [ 0.940605] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
  904. 27.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.083329] In-situ OAM (IOAM) with IPv6
  905. 27.95 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.085720] IPI shorthand broadcast: enabled
  906. 27.95 s [vm-test-run-centjes-e2e-test] client # [ 0.944722] ACPI: \_SB_.LNKD: Enabled at IRQ 11
  907. 27.95 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.089881] registered taskstats version 1
  908. 27.95 s [vm-test-run-centjes-e2e-test] client # [ 0.948897] PCI: CLS 0 bytes, default 64
  909. 27.95 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.092105] Loading compiled-in X.509 certificates
  910. 27.95 s [vm-test-run-centjes-e2e-test] client # [ 0.951116] Trying to unpack rootfs image as initramfs...
  911. 27.96 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.099065] Demotion targets for Node 0: null
  912. 27.96 s [vm-test-run-centjes-e2e-test] client # [ 0.955652] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns
  913. 27.96 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.101339] Key type .fscrypt registered
  914. 27.96 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.103365] Key type fscrypt-provisioning registered
  915. 27.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.105924] PM: Magic number: 6:287:939
  916. 27.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.108679] RAS: Correctable Errors collector initialized.
  917. 27.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.114051] Unstable clock detected, switching default tracing clock to "global"
  918. 27.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.114051] If you want to keep using the local clock, then add:
  919. 27.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.114051] "trace_clock=local"
  920. 27.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.114051] on the kernel command line
  921. 27.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.122903] clk: Disabling unused clocks
  922. 27.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.124870] PM: genpd: Disabling unused power domains
  923. 27.99 s [vm-test-run-centjes-e2e-test] client # [ 0.984922] Initialise system trusted keyrings
  924. 27.99 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.128323] Freeing unused decrypted memory: 2028K
  925. 27.99 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.131008] Freeing unused kernel image (initmem) memory: 3408K
  926. 27.99 s [vm-test-run-centjes-e2e-test] client # [ 0.990647] workingset: timestamp_bits=40 max_order=18 bucket_order=0
  927. 27.99 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.133843] Write protecting the kernel read-only data: 30720k
  928. 28.00 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.137051] Freeing unused kernel image (rodata/data gap) memory: 1756K
  929. 28.02 s [vm-test-run-centjes-e2e-test] client # [ 1.015585] Key type asymmetric registered
  930. 28.02 s [vm-test-run-centjes-e2e-test] client # [ 1.020719] Asymmetric key parser 'x509' registered
  931. 28.03 s [vm-test-run-centjes-e2e-test] client # [ 1.027726] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
  932. 28.04 s [vm-test-run-centjes-e2e-test] client # [ 1.035650] io scheduler mq-deadline registered
  933. 28.04 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.178393] x86/mm: Checked W+X mappings: passed, no W+X pages found.
  934. 28.04 s [vm-test-run-centjes-e2e-test] client # [ 1.037834] io scheduler kyber registered
  935. 28.04 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.181456] Run /init as init process
  936. 28.04 s [vm-test-run-centjes-e2e-test] client # [ 1.041838] Freeing initrd memory: 12300K
  937. 28.05 s [vm-test-run-centjes-e2e-test] client # [ 1.044089] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
  938. 28.05 s [vm-test-run-centjes-e2e-test] client # [ 1.047459] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
  939. 28.05 s [vm-test-run-centjes-e2e-test] client # [ 1.052088] Linux agpgart interface v0.103
  940. 28.06 s [vm-test-run-centjes-e2e-test] client # [ 1.054210] ACPI: bus type drm_connector registered
  941. 28.06 s [vm-test-run-centjes-e2e-test] client # [ 1.056899] usbcore: registered new interface driver usbserial_generic
  942. 28.06 s [vm-test-run-centjes-e2e-test] client # [ 1.059990] usbserial: USB Serial support registered for generic
  943. 28.07 s [vm-test-run-centjes-e2e-test] client # [ 1.062821] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled
  944. 28.07 s [vm-test-run-centjes-e2e-test] client # [ 1.066320] drop_monitor: Initializing network drop monitor service
  945. 28.07 s [vm-test-run-centjes-e2e-test] client # [ 1.069381] NET: Registered PF_INET6 protocol family
  946. 28.07 s [vm-test-run-centjes-e2e-test] client # [ 1.072397] Segment Routing with IPv6
  947. 28.08 s [vm-test-run-centjes-e2e-test] client # [ 1.074322] In-situ OAM (IOAM) with IPv6
  948. 28.08 s [vm-test-run-centjes-e2e-test] client # [ 1.076692] IPI shorthand broadcast: enabled
  949. 28.08 s [vm-test-run-centjes-e2e-test] client # [ 1.080816] registered taskstats version 1
  950. 28.08 s [vm-test-run-centjes-e2e-test] client # [ 1.082987] Loading compiled-in X.509 certificates
  951. 28.09 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.224370] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev
  952. 28.09 s [vm-test-run-centjes-e2e-test] client # [ 1.089844] Demotion targets for Node 0: null
  953. 28.09 s [vm-test-run-centjes-e2e-test] client # [ 1.092103] Key type .fscrypt registered
  954. 28.10 s [vm-test-run-centjes-e2e-test] client # [ 1.094145] Key type fscrypt-provisioning registered
  955. 28.10 s [vm-test-run-centjes-e2e-test] client # [ 1.096685] PM: Magic number: 6:287:939
  956. 28.10 s [vm-test-run-centjes-e2e-test] client # [ 1.099228] RAS: Correctable Errors collector initialized.
  957. 28.11 s [vm-test-run-centjes-e2e-test] client # [ 1.104688] Unstable clock detected, switching default tracing clock to "global"
  958. 28.11 s [vm-test-run-centjes-e2e-test] client # [ 1.104688] If you want to keep using the local clock, then add:
  959. 28.11 s [vm-test-run-centjes-e2e-test] client # [ 1.104688] "trace_clock=local"
  960. 28.11 s [vm-test-run-centjes-e2e-test] client # [ 1.104688] on the kernel command line
  961. 28.12 s [vm-test-run-centjes-e2e-test] client # [ 1.113920] clk: Disabling unused clocks
  962. 28.12 s [vm-test-run-centjes-e2e-test] client # [ 1.115931] PM: genpd: Disabling unused power domains
  963. 28.12 s [vm-test-run-centjes-e2e-test] client # [ 1.119335] Freeing unused decrypted memory: 2028K
  964. 28.12 s [vm-test-run-centjes-e2e-test] client # [ 1.122042] Freeing unused kernel image (initmem) memory: 3408K
  965. 28.13 s [vm-test-run-centjes-e2e-test] client # [ 1.124864] Write protecting the kernel read-only data: 30720k
  966. 28.13 s [vm-test-run-centjes-e2e-test] client # [ 1.127965] Freeing unused kernel image (rodata/data gap) memory: 1756K
  967. 28.17 s [vm-test-run-centjes-e2e-test] client # [ 1.169005] x86/mm: Checked W+X mappings: passed, no W+X pages found.
  968. 28.17 s [vm-test-run-centjes-e2e-test] client # [ 1.172041] Run /init as init process
  969. 28.22 s [vm-test-run-centjes-e2e-test] client # [ 1.214608] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev
  970. 28.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.383351] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
  971. 28.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.406964] uhci_hcd 0000:00:01.2: UHCI Host Controller
  972. 28.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.411223] serio: i8042 KBD port at 0x60,0x64 irq 1
  973. 28.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.414012] SCSI subsystem initialized
  974. 28.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.418629] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
  975. 28.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.422649] ACPI: \_SB_.LNKC: Enabled at IRQ 10
  976. 28.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.427631] serio: i8042 AUX port at 0x60,0x64 irq 12
  977. 28.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.437340] uhci_hcd 0000:00:01.2: detected 2 ports
  978. 28.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.446492] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100
  979. 28.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.453250] ACPI: \_SB_.LNKA: Enabled at IRQ 10
  980. 28.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.457781] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.12
  981. 28.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.461594] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
  982. 28.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.465015] usb usb1: Product: UHCI Host Controller
  983. 28.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.467472] usb usb1: Manufacturer: Linux 6.12.62 uhci_hcd
  984. 28.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.470189] usb usb1: SerialNumber: 0000:00:01.2
  985. 28.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.476746] scsi host0: ata_piix
  986. 28.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.480480] ACPI: \_SB_.LNKB: Enabled at IRQ 11
  987. 28.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.484824] scsi host1: ata_piix
  988. 28.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.489434] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 lpm-pol 0
  989. 28.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.493012] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0
  990. 28.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.504806] hub 1-0:1.0: USB hub found
  991. 28.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.511715] hub 1-0:1.0: 2 ports detected
  992. 28.37 s [vm-test-run-centjes-e2e-test] client # [ 1.368997] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
  993. 28.39 s [vm-test-run-centjes-e2e-test] client # [ 1.386859] SCSI subsystem initialized
  994. 28.40 s [vm-test-run-centjes-e2e-test] client # [ 1.399994] ACPI: \_SB_.LNKC: Enabled at IRQ 10
  995. 28.41 s [vm-test-run-centjes-e2e-test] client # [ 1.403769] serio: i8042 KBD port at 0x60,0x64 irq 1
  996. 28.41 s [vm-test-run-centjes-e2e-test] client # [ 1.410215] uhci_hcd 0000:00:01.2: UHCI Host Controller
  997. 28.42 s [vm-test-run-centjes-e2e-test] client # [ 1.415569] serio: i8042 AUX port at 0x60,0x64 irq 12
  998. 28.42 s [vm-test-run-centjes-e2e-test] client # [ 1.421573] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
  999. 28.44 s [vm-test-run-centjes-e2e-test] client # [ 1.437862] uhci_hcd 0000:00:01.2: detected 2 ports
  1000. 28.44 s [vm-test-run-centjes-e2e-test] client # [ 1.442968] ACPI: \_SB_.LNKA: Enabled at IRQ 10
  1001. 28.45 s [vm-test-run-centjes-e2e-test] client # [ 1.448274] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100
  1002. 28.46 s [vm-test-run-centjes-e2e-test] client # [ 1.455162] scsi host0: ata_piix
  1003. 28.46 s [vm-test-run-centjes-e2e-test] client # [ 1.458466] ACPI: \_SB_.LNKB: Enabled at IRQ 11
  1004. 28.46 s [vm-test-run-centjes-e2e-test] client # [ 1.461069] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.12
  1005. 28.47 s [vm-test-run-centjes-e2e-test] client # [ 1.464883] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
  1006. 28.47 s [vm-test-run-centjes-e2e-test] client # [ 1.468332] usb usb1: Product: UHCI Host Controller
  1007. 28.47 s [vm-test-run-centjes-e2e-test] client # [ 1.470788] usb usb1: Manufacturer: Linux 6.12.62 uhci_hcd
  1008. 28.48 s [vm-test-run-centjes-e2e-test] client # [ 1.473572] usb usb1: SerialNumber: 0000:00:01.2
  1009. 28.48 s [vm-test-run-centjes-e2e-test] client # [ 1.476039] scsi host1: ata_piix
  1010. 28.48 s [vm-test-run-centjes-e2e-test] client # [ 1.477887] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 lpm-pol 0
  1011. 28.48 s [vm-test-run-centjes-e2e-test] client # [ 1.481585] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0
  1012. 28.50 s [vm-test-run-centjes-e2e-test] client # [ 1.496898] hub 1-0:1.0: USB hub found
  1013. 28.50 s [vm-test-run-centjes-e2e-test] client # [ 1.502603] hub 1-0:1.0: 2 ports detected
  1014. 28.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.653696] ata2: found unknown device (class 0)
  1015. 28.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.657093] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
  1016. 28.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.662114] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5
  1017. 28.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.732636] usb 1-1: new full-speed USB device number 2 using uhci_hcd
  1018. 28.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.741815] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
  1019. 28.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.768353] virtio_blk virtio5: 1/0/0 default/read/poll queues
  1020. 28.64 s [vm-test-run-centjes-e2e-test] client # [ 1.637156] ata2: found unknown device (class 0)
  1021. 28.64 s [vm-test-run-centjes-e2e-test] client # [ 1.641138] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
  1022. 28.65 s [vm-test-run-centjes-e2e-test] client # [ 1.645951] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5
  1023. 28.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.794031] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)
  1024. 28.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.808260] netfs: FS-Cache loaded
  1025. 28.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.812980] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
  1026. 28.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.816164] cdrom: Uniform CD-ROM driver Revision: 3.20
  1027. 28.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.830356] 9pnet: Installing 9P2000 support
  1028. 28.73 s [vm-test-run-centjes-e2e-test] client # [ 1.722954] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
  1029. 28.73 s [vm-test-run-centjes-e2e-test] client # [ 1.729584] usb 1-1: new full-speed USB device number 2 using uhci_hcd
  1030. 28.75 s [vm-test-run-centjes-e2e-test] client # [ 1.752041] virtio_blk virtio5: 1/0/0 default/read/poll queues
  1031. 28.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.910953] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00
  1032. 28.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.914880] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10
  1033. 28.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.918392] usb 1-1: Product: QEMU USB Tablet
  1034. 28.78 s [vm-test-run-centjes-e2e-test] client # [ 1.776396] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)
  1035. 28.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.920642] usb 1-1: Manufacturer: QEMU
  1036. 28.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.922604] usb 1-1: SerialNumber: 28754-0000:00:01.2-1
  1037. 28.78 s [vm-test-run-centjes-e2e-test] client # [ 1.782845] netfs: FS-Cache loaded
  1038. 28.80 s [vm-test-run-centjes-e2e-test] client # [ 1.796736] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
  1039. 28.80 s [vm-test-run-centjes-e2e-test] client # [ 1.799886] cdrom: Uniform CD-ROM driver Revision: 3.20
  1040. 28.81 s [vm-test-run-centjes-e2e-test] client # [ 1.803726] 9pnet: Installing 9P2000 support
  1041. 28.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.936664] hid: raw HID events driver (C) Jiri Kosina
  1042. 28.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.969279] usbcore: registered new interface driver usbhid
  1043. 28.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.972016] usbhid: USB HID core driver
  1044. 28.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.977760] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2
  1045. 28.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.983287] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0
  1046. 28.91 s [vm-test-run-centjes-e2e-test] client # [ 1.905667] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00
  1047. 28.91 s [vm-test-run-centjes-e2e-test] client # [ 1.909569] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10
  1048. 28.91 s [vm-test-run-centjes-e2e-test] client # [ 1.912961] usb 1-1: Product: QEMU USB Tablet
  1049. 28.92 s [vm-test-run-centjes-e2e-test] client # [ 1.915171] usb 1-1: Manufacturer: QEMU
  1050. 28.92 s [vm-test-run-centjes-e2e-test] client # [ 1.917169] usb 1-1: SerialNumber: 28754-0000:00:01.2-1
  1051. 28.95 s [vm-test-run-centjes-e2e-test] client # [ 1.936237] hid: raw HID events driver (C) Jiri Kosina
  1052. 28.96 s [vm-test-run-centjes-e2e-test] client # [ 1.959826] usbcore: registered new interface driver usbhid
  1053. 28.96 s [vm-test-run-centjes-e2e-test] client # [ 1.962603] usbhid: USB HID core driver
  1054. 28.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.106485] EXT4-fs (vda): mounted filesystem a58cf4d2-0219-4a8c-84a7-269dfc704e6c r/w with ordered data mode. Quota mode: none.
  1055. 28.97 s [vm-test-run-centjes-e2e-test] client # [ 1.966382] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2
  1056. 28.98 s [vm-test-run-centjes-e2e-test] client # [ 1.971875] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0
  1057. 28.99 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.127153] 9p: Installing v9fs 9p2000 file system support
  1058. 29.10 s [vm-test-run-centjes-e2e-test] client # [ 2.095901] EXT4-fs (vda): mounted filesystem c00e5177-af61-4791-a5a3-b08482f95c0f r/w with ordered data mode. Quota mode: none.
  1059. 29.12 s [vm-test-run-centjes-e2e-test] client # [ 2.116855] 9p: Installing v9fs 9p2000 file system support
  1060. 29.21 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.350673] EXT4-fs (vda): re-mounted a58cf4d2-0219-4a8c-84a7-269dfc704e6c.
  1061. 29.22 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.355496] booting system configuration /nix/store/b4ylhqbb1k81nwvff9ifybzbhdcjb9l7-nixos-system-docsserver-test
  1062. 29.36 s [vm-test-run-centjes-e2e-test] client # [ 2.358625] EXT4-fs (vda): re-mounted c00e5177-af61-4791-a5a3-b08482f95c0f.
  1063. 29.37 s [vm-test-run-centjes-e2e-test] client # [ 2.363337] booting system configuration /nix/store/001k9r4jinw78ih5x5hxggb82ynlbkqn-nixos-system-client-test
  1064. 30.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 3.874340] systemd[1]: Inserted module 'autofs4'
  1065. 30.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 3.918803] systemd[1]: systemd 258.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN -IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE)
  1066. 30.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 3.933535] systemd[1]: Detected virtualization kvm.
  1067. 30.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 3.936147] systemd[1]: Detected architecture x86-64.
  1068. 30.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 3.938785] systemd[1]: Detected first boot.
  1069. 30.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 3.944049] systemd[1]: Initializing machine ID from random generator.
  1070. 30.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 3.967006] systemd[1]: Hostname set to <docsserver>.
  1071. 30.91 s [vm-test-run-centjes-e2e-test] client # [ 3.906790] systemd[1]: Inserted module 'autofs4'
  1072. 30.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 4.075253] systemd[1]: bpf-restrict-fs: LSM BPF program attached
  1073. 30.97 s [vm-test-run-centjes-e2e-test] client # [ 3.951920] systemd[1]: systemd 258.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN -IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE)
  1074. 30.97 s [vm-test-run-centjes-e2e-test] client # [ 3.967685] systemd[1]: Detected virtualization kvm.
  1075. 30.97 s [vm-test-run-centjes-e2e-test] client # [ 3.970307] systemd[1]: Detected architecture x86-64.
  1076. 30.97 s [vm-test-run-centjes-e2e-test] client # [ 3.972858] systemd[1]: Detected first boot.
  1077. 30.98 s [vm-test-run-centjes-e2e-test] client # [ 3.978129] systemd[1]: Initializing machine ID from random generator.
  1078. 31.01 s [vm-test-run-centjes-e2e-test] client # [ 4.003278] systemd[1]: Hostname set to <client>.
  1079. 31.07 s [vm-test-run-centjes-e2e-test] docsserver # [ 4.208732] systemd[1]: Applying preset policy.
  1080. 31.12 s [vm-test-run-centjes-e2e-test] client # [ 4.113775] systemd[1]: bpf-restrict-fs: LSM BPF program attached
  1081. 31.26 s [vm-test-run-centjes-e2e-test] client # [ 4.261704] systemd[1]: Applying preset policy.
  1082. 31.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 4.739854] systemd[1]: Populated /etc with preset unit settings.
  1083. 31.81 s [vm-test-run-centjes-e2e-test] client # [ 4.805075] systemd[1]: Populated /etc with preset unit settings.
  1084. 31.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.116107] systemd[1]: /etc/systemd/system/centjes-docs-site-production.service:3: Invalid URL, ignoring: /nix/store/afmrim8qnza0a65snqgh9m574hcav0m7-settings-check
  1085. 32.09 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.220305] systemd[1]: Queued start job for default target Multi-User System.
  1086. 32.11 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.250254] systemd[1]: Created slice Slice /system/getty.
  1087. 32.11 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.253273] systemd[1]: Created slice Slice /system/modprobe.
  1088. 32.12 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.256296] systemd[1]: Created slice User and Session Slice.
  1089. 32.12 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.259104] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
  1090. 32.12 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.262270] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
  1091. 32.12 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.265507] systemd[1]: Expecting device /dev/hvc0...
  1092. 32.13 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.267707] systemd[1]: Expecting device /dev/ttyS0...
  1093. 32.13 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.269780] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...
  1094. 32.13 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.272676] systemd[1]: Reached target Local Encrypted Volumes.
  1095. 32.13 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.274992] systemd[1]: Reached target Virtual Machines and Containers.
  1096. 32.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.277748] systemd[1]: Reached target Path Units.
  1097. 32.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.279761] systemd[1]: Reached target Remote File Systems.
  1098. 32.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.282033] systemd[1]: Reached target Slice Units.
  1099. 32.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.284163] systemd[1]: Reached target Swaps.
  1100. 32.15 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.289668] systemd[1]: Listening on Process Core Dump Socket.
  1101. 32.15 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.294282] systemd[1]: Listening on Credential Encryption/Decryption.
  1102. 32.16 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.297269] systemd[1]: Listening on Journal Audit Socket.
  1103. 32.16 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.299766] systemd[1]: Listening on Journal Socket (/dev/log).
  1104. 32.16 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.302287] systemd[1]: Listening on Journal Sockets.
  1105. 32.16 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.304796] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.
  1106. 32.17 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.307696] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
  1107. 32.17 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.311859] systemd[1]: Listening on udev Control Socket.
  1108. 32.17 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.314205] systemd[1]: Listening on udev Kernel Socket.
  1109. 32.18 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.320291] systemd[1]: Mounting Huge Pages File System...
  1110. 32.19 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.325697] systemd[1]: Mounting POSIX Message Queue File System...
  1111. 32.19 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.332514] systemd[1]: Mounting Kernel Debug File System...
  1112. 32.20 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.343731] systemd[1]: Mounting Kernel Trace File System...
  1113. 32.22 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.359116] systemd[1]: Starting Create List of Static Device Nodes...
  1114. 32.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.365906] systemd[1]: Load Kernel Module 9pnet_virtio was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!9pnet_virtio).
  1115. 32.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.382792] systemd[1]: Starting Load Kernel Module configfs...
  1116. 32.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.389227] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm).
  1117. 32.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.399402] systemd[1]: Starting Load Kernel Module efi_pstore...
  1118. 32.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.407468] systemd[1]: Starting Load Kernel Module fuse...
  1119. 32.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.412211] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
  1120. 32.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.417076] systemd[1]: Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67).
  1121. 32.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.429643] systemd[1]: Starting Journal Service...
  1122. 32.30 s [vm-test-run-centjes-e2e-test] client # [ 5.298231] systemd[1]: Queued start job for default target Multi-User System.
  1123. 32.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.441700] systemd[1]: Starting Load Kernel Modules...
  1124. 32.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.452716] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...
  1125. 32.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.461836] systemd[1]: Starting Remount Root and Kernel File Systems...
  1126. 32.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.467104] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
  1127. 32.33 s [vm-test-run-centjes-e2e-test] client # [ 5.329254] systemd[1]: Created slice Slice /system/getty.
  1128. 32.33 s [vm-test-run-centjes-e2e-test] client # [ 5.332306] systemd[1]: Created slice Slice /system/modprobe.
  1129. 32.34 s [vm-test-run-centjes-e2e-test] client # [ 5.335296] systemd[1]: Created slice User and Session Slice.
  1130. 32.34 s [vm-test-run-centjes-e2e-test] client # [ 5.338157] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
  1131. 32.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.482162] systemd[1]: Starting Coldplug All udev Devices...
  1132. 32.34 s [vm-test-run-centjes-e2e-test] client # [ 5.341364] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
  1133. 32.35 s [vm-test-run-centjes-e2e-test] client # [ 5.344646] systemd[1]: Expecting device /dev/hvc0...
  1134. 32.35 s [vm-test-run-centjes-e2e-test] client # [ 5.346688] systemd[1]: Expecting device /dev/ttyS0...
  1135. 32.35 s [vm-test-run-centjes-e2e-test] client # [ 5.348762] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...
  1136. 32.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.492234] systemd[1]: Mounted Huge Pages File System.
  1137. 32.35 s [vm-test-run-centjes-e2e-test] client # [ 5.351730] systemd[1]: Reached target Local Encrypted Volumes.
  1138. 32.36 s [vm-test-run-centjes-e2e-test] client # [ 5.354124] systemd[1]: Reached target Virtual Machines and Containers.
  1139. 32.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.497256] systemd[1]: Mounted POSIX Message Queue File System.
  1140. 32.36 s [vm-test-run-centjes-e2e-test] client # [ 5.356777] systemd[1]: Reached target Path Units.
  1141. 32.36 s [vm-test-run-centjes-e2e-test] client # [ 5.358736] systemd[1]: Reached target Remote File Systems.
  1142. 32.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.502412] systemd[1]: Mounted Kernel Debug File System.
  1143. 32.36 s [vm-test-run-centjes-e2e-test] client # [ 5.360964] systemd[1]: Reached target Slice Units.
  1144. 32.36 s [vm-test-run-centjes-e2e-test] client # [ 5.363012] systemd[1]: Reached target Swaps.
  1145. 32.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.506857] systemd[1]: Mounted Kernel Trace File System.
  1146. 32.37 s [vm-test-run-centjes-e2e-test] client # [ 5.368671] systemd[1]: Listening on Process Core Dump Socket.
  1147. 32.38 s [vm-test-run-centjes-e2e-test] client # [ 5.373625] systemd[1]: Listening on Credential Encryption/Decryption.
  1148. 32.38 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.517018] systemd[1]: Finished Create List of Static Device Nodes.
  1149. 32.38 s [vm-test-run-centjes-e2e-test] client # [ 5.376815] systemd[1]: Listening on Journal Audit Socket.
  1150. 32.38 s [vm-test-run-centjes-e2e-test] client # [ 5.379162] systemd[1]: Listening on Journal Socket (/dev/log).
  1151. 32.38 s [vm-test-run-centjes-e2e-test] client # [ 5.381797] systemd[1]: Listening on Journal Sockets.
  1152. 32.39 s [vm-test-run-centjes-e2e-test] client # [ 5.384219] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.
  1153. 32.39 s [vm-test-run-centjes-e2e-test] client # [ 5.387084] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
  1154. 32.39 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.530805] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...
  1155. 32.39 s [vm-test-run-centjes-e2e-test] client # [ 5.391267] systemd[1]: Listening on udev Control Socket.
  1156. 32.40 s [vm-test-run-centjes-e2e-test] client # [ 5.393769] systemd[1]: Listening on udev Kernel Socket.
  1157. 32.40 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.538965] systemd[1]: modprobe@configfs.service: Deactivated successfully.
  1158. 32.40 s [vm-test-run-centjes-e2e-test] client # [ 5.400208] systemd[1]: Mounting Huge Pages File System...
  1159. 32.41 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.547080] systemd[1]: Finished Load Kernel Module configfs.
  1160. 32.41 s [vm-test-run-centjes-e2e-test] client # [ 5.405707] systemd[1]: Mounting POSIX Message Queue File System...
  1161. 32.41 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.554017] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
  1162. 32.42 s [vm-test-run-centjes-e2e-test] client # [ 5.413490] systemd[1]: Mounting Kernel Debug File System...
  1163. 32.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.561990] systemd[1]: Finished Load Kernel Module efi_pstore.
  1164. 32.43 s [vm-test-run-centjes-e2e-test] client # [ 5.425960] systemd[1]: Mounting Kernel Trace File System...
  1165. 32.43 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.571515] systemd[1]: Mounting Kernel Configuration File System...
  1166. 32.44 s [vm-test-run-centjes-e2e-test] client # [ 5.440252] systemd[1]: Starting Create List of Static Device Nodes...
  1167. 32.45 s [vm-test-run-centjes-e2e-test] client # [ 5.449595] systemd[1]: Load Kernel Module 9pnet_virtio was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!9pnet_virtio).
  1168. 32.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.597393] systemd[1]: Mounted Kernel Configuration File System.
  1169. 32.48 s [vm-test-run-centjes-e2e-test] client # [ 5.473773] systemd[1]: Starting Load Kernel Module configfs...
  1170. 32.48 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.618843] EXT4-fs (vda): re-mounted a58cf4d2-0219-4a8c-84a7-269dfc704e6c.
  1171. 32.48 s [vm-test-run-centjes-e2e-test] client # [ 5.476238] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm).
  1172. 32.48 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.622398] loop: module loaded
  1173. 32.48 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.624765] systemd-journald[383]: Collecting audit messages is enabled.
  1174. 32.49 s [vm-test-run-centjes-e2e-test] client # [ 5.484960] systemd[1]: Starting Load Kernel Module efi_pstore...
  1175. 32.49 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.631791] fuse: init (API version 7.41)
  1176. 32.50 s [vm-test-run-centjes-e2e-test] client # [ 5.494820] systemd[1]: Starting Load Kernel Module fuse...
  1177. 32.50 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.640237] systemd[1]: Finished Load Kernel Modules.
  1178. 32.50 s [vm-test-run-centjes-e2e-test] client # [ 5.499897] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
  1179. 32.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.646398] systemd[1]: Finished Remount Root and Kernel File Systems.
  1180. 32.51 s [vm-test-run-centjes-e2e-test] client # [ 5.509010] systemd[1]: Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67).
  1181. 32.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.657791] systemd[1]: Starting Firewall...
  1182. 32.52 s [vm-test-run-centjes-e2e-test] client # [ 5.521731] systemd[1]: Starting Journal Service...
  1183. 32.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.662482] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
  1184. 32.53 s [vm-test-run-centjes-e2e-test] client # [ 5.531703] systemd[1]: Starting Load Kernel Modules...
  1185. 32.54 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.679381] systemd[1]: Starting Load/Save OS Random Seed...
  1186. 32.55 s [vm-test-run-centjes-e2e-test] client # [ 5.543161] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...
  1187. 32.55 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.690141] systemd[1]: Starting Apply Kernel Variables...
  1188. 32.56 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.694691] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
  1189. 32.56 s [vm-test-run-centjes-e2e-test] client # [ 5.555738] systemd[1]: Starting Remount Root and Kernel File Systems...
  1190. 32.56 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.704142] systemd[1]: modprobe@fuse.service: Deactivated successfully.
  1191. 32.57 s [vm-test-run-centjes-e2e-test] client # [ 5.561696] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
  1192. 32.57 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.711856] systemd[1]: Finished Load Kernel Module fuse.
  1193. 32.58 s [vm-test-run-centjes-e2e-test] client # [ 5.577757] systemd[1]: Starting Coldplug All udev Devices...
  1194. 32.58 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.720196] systemd[1]: Mounting FUSE Control File System...
  1195. 32.59 s [vm-test-run-centjes-e2e-test] client # [ 5.590710] systemd[1]: Mounted Huge Pages File System.
  1196. 32.60 s [vm-test-run-centjes-e2e-test] client # [ 5.595933] systemd[1]: Mounted POSIX Message Queue File System.
  1197. 32.60 s [vm-test-run-centjes-e2e-test] client # [ 5.602300] systemd[1]: Mounted Kernel Debug File System.
  1198. 32.61 s [vm-test-run-centjes-e2e-test] client # [ 5.606982] systemd[1]: Mounted Kernel Trace File System.
  1199. 32.61 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.749898] systemd[1]: Mounted FUSE Control File System.
  1200. 32.62 s [vm-test-run-centjes-e2e-test] client # [ 5.618780] systemd[1]: Finished Create List of Static Device Nodes.
  1201. 32.63 s [vm-test-run-centjes-e2e-test] client # [ 5.625711] systemd[1]: modprobe@configfs.service: Deactivated successfully.
  1202. 32.64 s [vm-test-run-centjes-e2e-test] client # [ 5.634754] systemd[1]: Finished Load Kernel Module configfs.
  1203. 32.64 s [vm-test-run-centjes-e2e-test] client # [ 5.640321] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
  1204. 32.65 s [vm-test-run-centjes-e2e-test] client # [ 5.648032] systemd[1]: Finished Load Kernel Module efi_pstore.
  1205. 32.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.799121] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
  1206. 32.66 s [vm-test-run-centjes-e2e-test] client # [ 5.660302] systemd[1]: Mounting Kernel Configuration File System...
  1207. 32.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.809092] systemd[1]: Starting Create Static Device Nodes in /dev...
  1208. 32.67 s [vm-test-run-centjes-e2e-test] client # [ 5.672618] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...
  1209. 32.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.831691] systemd[1]: Finished Load/Save OS Random Seed.
  1210. 32.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.838361] systemd[1]: Reached target First Boot Complete.
  1211. 32.70 s [vm-test-run-centjes-e2e-test] client # [ 5.696884] systemd[1]: Mounted Kernel Configuration File System.
  1212. 32.70 s [vm-test-run-centjes-e2e-test] client # [ 5.700406] fuse: init (API version 7.41)
  1213. 32.71 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.845489] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.
  1214. 32.71 s [vm-test-run-centjes-e2e-test] client # [ 5.703402] EXT4-fs (vda): re-mounted c00e5177-af61-4791-a5a3-b08482f95c0f.
  1215. 32.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.856064] systemd[1]: Finished Apply Kernel Variables.
  1216. 32.72 s [vm-test-run-centjes-e2e-test] client # [ 5.716786] loop: module loaded
  1217. 32.72 s [vm-test-run-centjes-e2e-test] client # [ 5.721926] systemd[1]: modprobe@fuse.service: Deactivated successfully.
  1218. 32.73 s [vm-test-run-centjes-e2e-test] client # [ 5.727684] systemd-journald[389]: Collecting audit messages is enabled.
  1219. 32.73 s [vm-test-run-centjes-e2e-test] client # [ 5.730658] systemd[1]: Finished Load Kernel Module fuse.
  1220. 32.74 s [vm-test-run-centjes-e2e-test] client # [ 5.735728] systemd[1]: Mounting FUSE Control File System...
  1221. 32.75 s [vm-test-run-centjes-e2e-test] client # [ 5.745869] systemd[1]: Finished Remount Root and Kernel File Systems.
  1222. 32.76 s [vm-test-run-centjes-e2e-test] client # [ 5.758690] systemd[1]: Finished Load Kernel Modules.
  1223. 32.77 s [vm-test-run-centjes-e2e-test] client # [ 5.772204] systemd[1]: Starting Firewall...
  1224. 32.78 s [vm-test-run-centjes-e2e-test] client # [ 5.776649] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
  1225. 32.80 s [vm-test-run-centjes-e2e-test] client # [ 5.793604] systemd[1]: Starting Load/Save OS Random Seed...
  1226. 32.81 s [vm-test-run-centjes-e2e-test] client # [ 5.804658] systemd[1]: Starting Apply Kernel Variables...
  1227. 32.81 s [vm-test-run-centjes-e2e-test] client # [ 5.809879] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
  1228. 32.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.954359] systemd[1]: Finished Create Static Device Nodes in /dev.
  1229. 32.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.960052] systemd[1]: Reached target Preparation for Local File Systems.
  1230. 32.82 s [vm-test-run-centjes-e2e-test] client # [ 5.820611] systemd[1]: Mounted FUSE Control File System.
  1231. 32.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.968797] systemd[1]: Starting Rule-based Manager for Device Events and Files...
  1232. 32.90 s [vm-test-run-centjes-e2e-test] client # [ 5.897385] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.
  1233. 32.91 s [vm-test-run-centjes-e2e-test] client # [ 5.912041] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
  1234. 32.93 s [vm-test-run-centjes-e2e-test] client # [ 5.925107] systemd[1]: Starting Create Static Device Nodes in /dev...
  1235. 32.95 s [vm-test-run-centjes-e2e-test] client # [ 5.944317] systemd[1]: Finished Load/Save OS Random Seed.
  1236. 32.95 s [vm-test-run-centjes-e2e-test] client # [ 5.950395] systemd[1]: Reached target First Boot Complete.
  1237. 32.96 s [vm-test-run-centjes-e2e-test] client # [ 5.958292] systemd[1]: Finished Apply Kernel Variables.
  1238. 33.01 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.147247] systemd[1]: Started Journal Service.
  1239. 33.01 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.765589] systemd-modules-load[384]: Inserted module 'loop'
  1240. 33.03 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.773708] systemd-oomd[385]: [0;1;38:5:185mNo swap; memory pressure usage will be degraded
  1241. 33.04 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.789617] systemd-udevd[411]: Using default interface naming scheme 'v258'.
  1242. 33.05 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.803192] systemd[1]: Starting Flush Journal to Persistent Storage...
  1243. 33.07 s [vm-test-run-centjes-e2e-test] client # [ 6.065715] systemd[1]: Finished Create Static Device Nodes in /dev.
  1244. 33.07 s [vm-test-run-centjes-e2e-test] client # [ 6.072015] systemd[1]: Reached target Preparation for Local File Systems.
  1245. 33.08 s [vm-test-run-centjes-e2e-test] client # [ 6.081690] systemd[1]: Starting Rule-based Manager for Device Events and Files...
  1246. 33.10 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.241535] systemd-journald[383]: Received client request to flush runtime journal.
  1247. 33.25 s [vm-test-run-centjes-e2e-test] client # [ 6.247623] systemd[1]: Started Journal Service.
  1248. 33.26 s [vm-test-run-centjes-e2e-test] client # [ 5.865375] systemd-modules-load[390]: Inserted module 'loop'
  1249. 33.27 s [vm-test-run-centjes-e2e-test] client # [ 5.874655] systemd-oomd[391]: [0;1;38:5:185mNo swap; memory pressure usage will be degraded
  1250. 33.29 s [vm-test-run-centjes-e2e-test] client # [ 5.893317] systemd-udevd[417]: Using default interface naming scheme 'v258'.
  1251. 33.30 s [vm-test-run-centjes-e2e-test] client # [ 5.907152] systemd[1]: Starting Flush Journal to Persistent Storage...
  1252. 33.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.079799] systemd[1]: Finished Coldplug All udev Devices.
  1253. 33.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.087597] systemd[1]: Started Rule-based Manager for Device Events and Files.
  1254. 33.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.095401] systemd[1]: Finished Flush Journal to Persistent Storage.
  1255. 33.35 s [vm-test-run-centjes-e2e-test] client # [ 6.347871] systemd-journald[389]: Received client request to flush runtime journal.
  1256. 33.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.107393] systemd[1]: Mounting /run/wrappers...
  1257. 33.39 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.146230] systemd[1]: Mounted /run/wrappers.
  1258. 33.40 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.152923] systemd[1]: Reached target Local File Systems.
  1259. 33.41 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.161324] systemd[1]: Listening on Boot Entries Service Socket.
  1260. 33.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.171193] systemd[1]: Starting Create SUID/SGID Wrappers...
  1261. 33.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.176539] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.
  1262. 33.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.193947] systemd[1]: Starting Save Transient machine-id to Disk...
  1263. 33.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.201730] systemd[1]: Starting Create System Files and Directories...
  1264. 33.58 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.322460] systemd[1]: Finished Create System Files and Directories.
  1265. 33.58 s [vm-test-run-centjes-e2e-test] client # [ 6.195322] systemd[1]: Finished Coldplug All udev Devices.
  1266. 33.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.336647] systemd[1]: Starting Rebuild Journal Catalog...
  1267. 33.60 s [vm-test-run-centjes-e2e-test] client # [ 6.204828] systemd[1]: Started Rule-based Manager for Device Events and Files.
  1268. 33.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.350672] systemd[1]: Starting Record System Boot/Shutdown in UTMP...
  1269. 33.61 s [vm-test-run-centjes-e2e-test] client # [ 6.218265] systemd[1]: Finished Flush Journal to Persistent Storage.
  1270. 33.61 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.363211] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
  1271. 33.62 s [vm-test-run-centjes-e2e-test] client # [ 6.225992] systemd[1]: Mounting /run/wrappers...
  1272. 33.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.372871] systemd[1]: Finished Save Transient machine-id to Disk.
  1273. 33.65 s [vm-test-run-centjes-e2e-test] client # [ 6.254893] systemd[1]: Mounted /run/wrappers.
  1274. 33.66 s [vm-test-run-centjes-e2e-test] client # [ 6.266219] systemd[1]: Reached target Local File Systems.
  1275. 33.67 s [vm-test-run-centjes-e2e-test] client # [ 6.277445] systemd[1]: Listening on Boot Entries Service Socket.
  1276. 33.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.425256] systemd[1]: Finished Record System Boot/Shutdown in UTMP.
  1277. 33.68 s [vm-test-run-centjes-e2e-test] client # [ 6.286342] systemd[1]: Starting Create SUID/SGID Wrappers...
  1278. 33.70 s [vm-test-run-centjes-e2e-test] client # [ 6.296609] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.
  1279. 33.71 s [vm-test-run-centjes-e2e-test] client # [ 6.313437] systemd[1]: Starting Save Transient machine-id to Disk...
  1280. 33.72 s [vm-test-run-centjes-e2e-test] client # [ 6.323414] systemd[1]: Starting Create System Files and Directories...
  1281. 33.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.501517] systemd[1]: Finished Rebuild Journal Catalog.
  1282. 33.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.508590] systemd[1]: Starting Update is Completed...
  1283. 33.79 s [vm-test-run-centjes-e2e-test] client # [ 6.399219] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
  1284. 33.81 s [vm-test-run-centjes-e2e-test] client # [ 6.413343] systemd[1]: Finished Save Transient machine-id to Disk.
  1285. 33.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.573354] systemd[1]: Finished Update is Completed.
  1286. 33.85 s [vm-test-run-centjes-e2e-test] client # [ 6.451350] systemd[1]: Finished Create System Files and Directories.
  1287. 33.86 s [vm-test-run-centjes-e2e-test] client # [ 6.463521] systemd[1]: Starting Rebuild Journal Catalog...
  1288. 33.87 s [vm-test-run-centjes-e2e-test] client # [ 6.474989] systemd[1]: Starting Record System Boot/Shutdown in UTMP...
  1289. 33.90 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.652369] systemd[1]: Found device /dev/hvc0.
  1290. 33.94 s [vm-test-run-centjes-e2e-test] client # [ 6.546882] systemd[1]: Finished Record System Boot/Shutdown in UTMP.
  1291. 33.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.692434] systemd[1]: Found device /dev/ttyS0.
  1292. 33.96 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.699457] (udev-worker)[488]: [0;1;38:5:185meth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
  1293. 33.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.720607] (udev-worker)[488]: Network interface NamePolicy= disabled on kernel command line.
  1294. 33.99 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.736542] (udev-worker)[482]: Network interface NamePolicy= disabled on kernel command line.
  1295. 34.00 s [vm-test-run-centjes-e2e-test] client # [ 6.607368] systemd[1]: Finished Rebuild Journal Catalog.
  1296. 34.01 s [vm-test-run-centjes-e2e-test] client # [ 6.614543] systemd[1]: Starting Update is Completed...
  1297. 34.07 s [vm-test-run-centjes-e2e-test] client # [ 6.683562] systemd[1]: Finished Update is Completed.
  1298. 34.15 s [vm-test-run-centjes-e2e-test] client # [ 6.763267] systemd[1]: Found device /dev/hvc0.
  1299. 34.18 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.928856] systemd[1]: Found device Virtio network device.
  1300. 34.21 s [vm-test-run-centjes-e2e-test] client # [ 6.819770] systemd[1]: Found device /dev/ttyS0.
  1301. 34.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.983298] systemd[1]: Finished Firewall.
  1302. 34.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.998433] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.
  1303. 34.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.007405] systemd[1]: Finished Create SUID/SGID Wrappers.
  1304. 34.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.015225] systemd[1]: Reached target System Initialization.
  1305. 34.27 s [vm-test-run-centjes-e2e-test] client # [ 6.870696] (udev-worker)[486]: Network interface NamePolicy= disabled on kernel command line.
  1306. 34.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.021594] systemd[1]: Started Discard unused filesystem blocks once a week.
  1307. 34.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.034776] systemd[1]: Started Daily Cleanup of Temporary Directories.
  1308. 34.30 s [vm-test-run-centjes-e2e-test] client # [ 6.894523] (udev-worker)[499]: [0;1;38:5:185meth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
  1309. 34.31 s [vm-test-run-centjes-e2e-test] client # [ 6.913288] (udev-worker)[499]: Network interface NamePolicy= disabled on kernel command line.
  1310. 34.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.056193] systemd[1]: Reached target Timer Units.
  1311. 34.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.068493] systemd[1]: Listening on D-Bus System Message Bus Socket.
  1312. 34.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.079961] systemd[1]: Listening on Nix Daemon Socket.
  1313. 34.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.089827] systemd[1]: Listening on Hostname Service Socket.
  1314. 34.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.114547] systemd[1]: Reached target Socket Units.
  1315. 34.38 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.129445] systemd[1]: Reached target Basic System.
  1316. 34.40 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.145602] systemd[1]: Started backdoor.service.
  1317. 34.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.161332] systemd[1]: Starting Import lastlog data into lastlog2 database...
  1318. 34.43 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.173740] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
  1319. 34.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.192281] systemd[1]: Started Reset console on configuration changes.
  1320. 34.45 s [vm-test-run-centjes-e2e-test] docsserver # connecting to host...
  1321. 34.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.208163] systemd[1]: Starting resolvconf update...
  1322. 34.48 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.218154] systemd[1]: Started Centjes docs site production Service.
  1323. 34.50 s [vm-test-run-centjes-e2e-test] docsserver: Guest shell says: b'Spawning backdoor root shell...\n'
  1324. 34.51 s [vm-test-run-centjes-e2e-test] docsserver: connected to guest root shell
  1325. 34.51 s [vm-test-run-centjes-e2e-test] docsserver: (connecting took 8.27 seconds)
  1326. 34.51 s [vm-test-run-centjes-e2e-test] docsserver: (finished: waiting for the VM to finish booting, in 8.27 seconds)
  1327. 34.51 s [vm-test-run-centjes-e2e-test] client # [ 7.105637] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.
  1328. 34.51 s [vm-test-run-centjes-e2e-test] client # [ 7.117576] systemd[1]: Finished Create SUID/SGID Wrappers.
  1329. 34.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.233704] nsncd[623]: Apr 27 13:55:03.540 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
  1330. 34.52 s [vm-test-run-centjes-e2e-test] client # [ 7.124717] systemd[1]: Reached target System Initialization.
  1331. 34.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.259728] systemd[1]: Starting D-Bus System Message Bus...
  1332. 34.53 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.275828] systemd[1]: Finished Import lastlog data into lastlog2 database.
  1333. 34.54 s [vm-test-run-centjes-e2e-test] client # [ 7.136863] systemd[1]: Started Discard unused filesystem blocks once a week.
  1334. 34.54 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.289299] systemd[1]: Started Name Service Cache Daemon (nsncd).
  1335. 34.55 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.296982] systemd[1]: Reached target Host and Network Name Lookups.
  1336. 34.56 s [vm-test-run-centjes-e2e-test] client # [ 7.159556] systemd[1]: Started Daily Cleanup of Temporary Directories.
  1337. 34.57 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.306974] dbus-daemon[627]: dbus[627]: Unknown username "systemd-timesync" in message bus configuration file
  1338. 34.57 s [vm-test-run-centjes-e2e-test] client # [ 7.174612] systemd[1]: Reached target Timer Units.
  1339. 34.58 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.327981] systemd[1]: Reached target User and Group Name Lookups.
  1340. 34.59 s [vm-test-run-centjes-e2e-test] client # [ 7.187513] systemd[1]: Listening on D-Bus System Message Bus Socket.
  1341. 34.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.335910] systemd[1]: Starting User Login Management...
  1342. 34.60 s [vm-test-run-centjes-e2e-test] client # [ 7.202571] systemd[1]: Listening on Nix Daemon Socket.
  1343. 34.61 s [vm-test-run-centjes-e2e-test] client # [ 7.221562] systemd[1]: Listening on Hostname Service Socket.
  1344. 34.63 s [vm-test-run-centjes-e2e-test] client # [ 7.233390] systemd[1]: Reached target Socket Units.
  1345. 34.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.375760] systemd[1]: Started D-Bus System Message Bus.
  1346. 34.65 s [vm-test-run-centjes-e2e-test] client # [ 7.252529] systemd[1]: Reached target Basic System.
  1347. 34.66 s [vm-test-run-centjes-e2e-test] client # [ 7.264882] systemd[1]: Started backdoor.service.
  1348. 34.67 s [vm-test-run-centjes-e2e-test] client # [ 7.277380] systemd[1]: Starting Import lastlog data into lastlog2 database...
  1349. 34.68 s [vm-test-run-centjes-e2e-test] client # connecting to host...
  1350. 34.70 s [vm-test-run-centjes-e2e-test] client # [ 7.295696] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
  1351. 34.71 s [vm-test-run-centjes-e2e-test] client # [ 7.313204] systemd[1]: Started Reset console on configuration changes.
  1352. 34.71 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.848296] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
  1353. 34.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.469529] systemd-logind[649]: New seat seat0.
  1354. 34.72 s [vm-test-run-centjes-e2e-test] client # [ 7.325512] systemd[1]: Starting resolvconf update...
  1355. 34.73 s [vm-test-run-centjes-e2e-test] client # [ 7.339417] systemd[1]: Finished Firewall.
  1356. 34.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.872919] mousedev: PS/2 mouse device common for all mice
  1357. 34.74 s [vm-test-run-centjes-e2e-test] client # [ 7.346677] systemd[1]: Found device Virtio network device.
  1358. 34.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.493457] systemd[1]: Started User Login Management.[ 7.883192] ACPI: button: Power Button [PWRF]
  1359. 34.74 s [vm-test-run-centjes-e2e-test] docsserver #
  1360. 34.76 s [vm-test-run-centjes-e2e-test] client # [ 7.359580] nsncd[626]: Apr 27 13:55:03.788 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
  1361. 34.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.506876] systemd[1]: Stopped target Host and Network Name Lookups.
  1362. 34.77 s [vm-test-run-centjes-e2e-test] client # [ 7.379790] systemd[1]: Starting D-Bus System Message Bus...
  1363. 34.78 s [vm-test-run-centjes-e2e-test] client # [ 7.390169] systemd[1]: Finished Import lastlog data into lastlog2 database.
  1364. 34.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.527174] systemd[1]: Stopping Host and Network Name Lookups...
  1365. 34.79 s [vm-test-run-centjes-e2e-test] client # [ 7.399529] systemd[1]: Started Name Service Cache Daemon (nsncd).
  1366. 34.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.542843] systemd[1]: Stopped target User and Group Name Lookups.
  1367. 34.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.554711] systemd[1]: Stopping User and Group Name Lookups...
  1368. 34.81 s [vm-test-run-centjes-e2e-test] client # [ 7.411493] dbus-daemon[630]: dbus[630]: Unknown username "systemd-timesync" in message bus configuration file
  1369. 34.82 s [vm-test-run-centjes-e2e-test] client # [ 7.427170] systemd[1]: Reached target Host and Network Name Lookups.
  1370. 34.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.565927] systemd[1]: Starting linger-users.service...
  1371. 34.83 s [vm-test-run-centjes-e2e-test] client # [ 7.435172] systemd[1]: Reached target User and Group Name Lookups.
  1372. 34.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.576690] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...
  1373. 34.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.588496] systemd[1]: nscd.service: Deactivated successfully.
  1374. 34.84 s [vm-test-run-centjes-e2e-test] client # [ 7.442727] systemd[1]: Starting User Login Management...
  1375. 34.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.599373] systemd[1]: Stopped Name Service Cache Daemon (nsncd).
  1376. 34.86 s [vm-test-run-centjes-e2e-test] client # [ 7.465293] systemd[1]: Started D-Bus System Message Bus.
  1377. 34.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.615737] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
  1378. 34.88 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.628229] systemd[1]: linger-users.service: Deactivated successfully.
  1379. 34.89 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.636585] systemd[1]: Finished linger-users.service.
  1380. 34.90 s [vm-test-run-centjes-e2e-test] client # [ 7.507378] systemd[1]: Stopped target Host and Network Name Lookups.
  1381. 34.90 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.653242] systemd[1]: Started Name Service Cache Daemon (nsncd).
  1382. 34.91 s [vm-test-run-centjes-e2e-test] client # [ 7.517779] systemd[1]: Stopping Host and Network Name Lookups...
  1383. 34.92 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.660945] nsncd[699]: Apr 27 13:55:03.974 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
  1384. 34.92 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.675568] systemd[1]: Reached target Host and Network Name Lookups.[ 8.064152] rtc_cmos 00:05: RTC can wake from S4
  1385. 34.92 s [vm-test-run-centjes-e2e-test] docsserver #
  1386. 34.93 s [vm-test-run-centjes-e2e-test] client # [ 7.533561] systemd[1]: Stopped target User and Group Name Lookups.
  1387. 34.93 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.681172] systemd[1]: Reached target User and Group Name Lookups.[ 8.070171] parport_pc 00:03: reported by Plug and Play ACPI
  1388. 34.93 s [vm-test-run-centjes-e2e-test] docsserver #
  1389. 34.93 s [vm-test-run-centjes-e2e-test] client # [ 7.542154] systemd[1]: Stopping User and Group Name Lookups...
  1390. 34.94 s [vm-test-run-centjes-e2e-test] client # [ 7.547699] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...
  1391. 34.95 s [vm-test-run-centjes-e2e-test] client # [ 7.557885] systemd[1]: nscd.service: Deactivated successfully.
  1392. 34.96 s [vm-test-run-centjes-e2e-test] client # [ 7.566267] systemd[1]: Stopped Name Service Cache Daemon (nsncd).
  1393. 34.96 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.705865] systemd[1]: Finished resolvconf update.
  1394. 34.97 s [vm-test-run-centjes-e2e-test] client # [ 7.575298] systemd[1]: Starting Name Service Cache Daemon (nsncd)...[ 7.964353] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
  1395. 34.97 s [vm-test-run-centjes-e2e-test] client #
  1396. 34.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.717343] systemd[1]: Reached target Preparation for Network.
  1397. 34.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.727978] systemd[1]: Starting DHCP Client...
  1398. 34.97 s [vm-test-run-centjes-e2e-test] client # [ 7.584890] systemd-logind[652]: New seat seat0.
  1399. 34.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.733951] systemd[1]: Starting Address configuration of eth1...
  1400. 34.98 s [vm-test-run-centjes-e2e-test] client # [ 7.590676] systemd[1]: Started User Login Management.
  1401. 34.99 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.133441] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]
  1402. 34.99 s [vm-test-run-centjes-e2e-test] client # [ 7.599864] systemd[1]: Starting linger-users.service...
  1403. 35.00 s [vm-test-run-centjes-e2e-test] client # [ 7.997159] mousedev: PS/2 mouse device common for all mice
  1404. 35.00 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.140707] rtc_cmos 00:05: registered as rtc0
  1405. 35.01 s [vm-test-run-centjes-e2e-test] client # [ 8.008653] ACPI: button: Power Button [PWRF]
  1406. 35.01 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.155110] Floppy drive(s): fd0 is 2.88M AMI BIOS
  1407. 35.02 s [vm-test-run-centjes-e2e-test] client # [ 7.631946] systemd[1]: Started Name Service Cache Daemon (nsncd).
  1408. 35.04 s [vm-test-run-centjes-e2e-test] client # [ 7.639272] nsncd[690]: Apr 27 13:55:04.088 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
  1409. 35.04 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.181131] rtc_cmos 00:05: setting system clock to 2026-04-27T13:55:03 UTC (1777298103)
  1410. 35.04 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.185088] systemd-journald[383]: Time jumped backwards, rotating.
  1411. 35.05 s [vm-test-run-centjes-e2e-test] client # [ 7.658170] systemd[1]: Reached target Host and Network Name Lookups.
  1412. 35.05 s [vm-test-run-centjes-e2e-test] client # [ 7.664562] systemd[1]: Reached target User and Group Name Lookups.
  1413. 35.06 s [vm-test-run-centjes-e2e-test] client # [ 7.670347] systemd[1]: linger-users.service: Deactivated successfully.
  1414. 35.06 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.204311] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs
  1415. 35.07 s [vm-test-run-centjes-e2e-test] client # [ 7.675781] systemd[1]: Finished linger-users.service.
  1416. 35.07 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.213649] FDC 0 is a S82078B
  1417. 35.10 s [vm-test-run-centjes-e2e-test] client # [ 7.703658] systemd[1]: Finished resolvconf update.
  1418. 35.10 s [vm-test-run-centjes-e2e-test] client # [ 7.711510] systemd[1]: Reached target Preparation for Network.
  1419. 35.11 s [vm-test-run-centjes-e2e-test] client # [ 7.720517] systemd[1]: Starting DHCP Client...
  1420. 35.12 s [vm-test-run-centjes-e2e-test] client # [ 7.726305] systemd[1]: Starting Address configuration of eth1...
  1421. 35.12 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.869480] network-addresses-eth1-start[730]: adding address 192.168.1.2/24... done
  1422. 35.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.888207] network-addresses-eth1-start[730]: adding address 2001:db8:1::2/64... done
  1423. 35.16 s [vm-test-run-centjes-e2e-test] client # [ 8.157327] rtc_cmos 00:05: RTC can wake from S4
  1424. 35.16 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.916159] systemd[1]: Finished Address configuration of eth1.
  1425. 35.18 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.923759] systemd[1]: Starting Networking Setup...
  1426. 35.19 s [vm-test-run-centjes-e2e-test] client # [ 8.190367] parport_pc 00:03: reported by Plug and Play ACPI
  1427. 35.19 s [vm-test-run-centjes-e2e-test] client # [ 8.193107] Floppy drive(s): fd0 is 2.88M AMI BIOS
  1428. 35.21 s [vm-test-run-centjes-e2e-test] client # [ 8.207135] rtc_cmos 00:05: registered as rtc0
  1429. 35.21 s [vm-test-run-centjes-e2e-test] client # [ 8.211260] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]
  1430. 35.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.369220] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
  1431. 35.23 s [vm-test-run-centjes-e2e-test] client # [ 7.842161] network-addresses-eth1-start[719]: adding address 192.168.1.1/24... done
  1432. 35.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.989718] dhcpcd[737]: dhcpcd-10.2.4 starting
  1433. 35.25 s [vm-test-run-centjes-e2e-test] client # [ 8.246237] FDC 0 is a S82078B
  1434. 35.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.000707] dhcpcd[752]: dev: loaded udev
  1435. 35.26 s [vm-test-run-centjes-e2e-test] client # [ 7.865813] network-addresses-eth1-start[719]: adding address 2001:db8:1::1/64... done[ 8.259087] rtc_cmos 00:05: setting system clock to 2026-04-27T13:55:05 UTC (1777298105)
  1436. 35.26 s [vm-test-run-centjes-e2e-test] client #
  1437. 35.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.412350] 8021q: 802.1Q VLAN Support v1.8
  1438. 35.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.415643] 8021q: adding VLAN 0 to HW filter on device eth1
  1439. 35.29 s [vm-test-run-centjes-e2e-test] client # [ 7.895432] systemd[1]: Finished Address configuration of eth1.
  1440. 35.29 s [vm-test-run-centjes-e2e-test] client # [ 7.903427] systemd[1]: Starting Networking Setup...
  1441. 35.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.441410] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input4
  1442. 35.31 s [vm-test-run-centjes-e2e-test] client # [ 8.303798] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs
  1443. 35.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.457034] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD
  1444. 35.33 s [vm-test-run-centjes-e2e-test] client # [ 7.938618] dhcpcd[726]: dhcpcd-10.2.4 starting
  1445. 35.34 s [vm-test-run-centjes-e2e-test] client # [ 8.335692] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
  1446. 35.35 s [vm-test-run-centjes-e2e-test] client # [ 7.958256] dhcpcd[738]: dev: loaded udev
  1447. 35.37 s [vm-test-run-centjes-e2e-test] client # [ 8.364899] 8021q: 802.1Q VLAN Support v1.8
  1448. 35.37 s [vm-test-run-centjes-e2e-test] client # [ 8.368595] 8021q: adding VLAN 0 to HW filter on device eth1
  1449. 35.38 s [vm-test-run-centjes-e2e-test] client # [ 8.373352] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD
  1450. 35.38 s [vm-test-run-centjes-e2e-test] client # [ 8.380356] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console
  1451. 35.40 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.544513] cfg80211: Loading compiled-in X.509 certificates for regulatory database
  1452. 35.41 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.550294] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console
  1453. 35.41 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.555154] cryptd: max_cpu_qlen set to 1000
  1454. 35.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.562098] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6
  1455. 35.43 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.568171] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5
  1456. 35.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.204415] systemd[1]: Finished Networking Setup.
  1457. 35.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.213469] systemd[1]: Reached target Network.
  1458. 35.47 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.220433] systemd[1]: Starting Permit User Sessions...
  1459. 35.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.603709] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
  1460. 35.53 s [vm-test-run-centjes-e2e-test] client # [ 8.502658] cfg80211: Loading compiled-in X.509 certificates for regulatory database
  1461. 35.53 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.603806] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
  1462. 35.55 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.605335] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
  1463. 35.56 s [vm-test-run-centjes-e2e-test] client # [ 8.165182] systemd[1]: Finished Networking Setup.
  1464. 35.56 s [vm-test-run-centjes-e2e-test] client # [ 8.171342] systemd[1]: Reached target Network.
  1465. 35.57 s [vm-test-run-centjes-e2e-test] client # [ 8.177670] systemd[1]: Starting Permit User Sessions...
  1466. 35.57 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.605338] cfg80211: failed to load regulatory.db
  1467. 35.58 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.643801] AES CTR mode by8 optimization enabled
  1468. 35.59 s [vm-test-run-centjes-e2e-test] client # [ 8.524919] Console: switching to colour dummy device 80x25
  1469. 35.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.652158] Console: switching to colour dummy device 80x25
  1470. 35.60 s [vm-test-run-centjes-e2e-test] client # [ 8.533971] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
  1471. 35.61 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.696677] 8021q: adding VLAN 0 to HW filter on device eth0
  1472. 35.62 s [vm-test-run-centjes-e2e-test] client # [ 8.534071] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
  1473. 35.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.310782] dhcpcd[752]: eth0: waiting for carrier
  1474. 35.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.384692] systemd[1]: Starting Virtual Console Setup...[ 8.772997] [drm] Found bochs VGA, ID 0xb0c5.
  1475. 35.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.774112] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.
  1476. 35.63 s [vm-test-run-centjes-e2e-test] client # [ 8.535619] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
  1477. 35.63 s [vm-test-run-centjes-e2e-test] docsserver #
  1478. 35.65 s [vm-test-run-centjes-e2e-test] client # [ 8.535624] cfg80211: failed to load regulatory.db
  1479. 35.66 s [vm-test-run-centjes-e2e-test] client # [ 8.560085] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input4
  1480. 35.68 s [vm-test-run-centjes-e2e-test] client # [ 8.592786] cryptd: max_cpu_qlen set to 1000
  1481. 35.68 s [vm-test-run-centjes-e2e-test] client # [ 8.609734] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6
  1482. 35.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.436236] systemd[1]: Finished Permit User Sessions.
  1483. 35.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.446240] systemd[1]: Started Getty on tty1.
  1484. 35.70 s [vm-test-run-centjes-e2e-test] client # [ 8.610238] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5
  1485. 35.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.450727] systemd[1]: Reached target Login Prompts.
  1486. 35.72 s [vm-test-run-centjes-e2e-test] client # [ 8.637014] 8021q: adding VLAN 0 to HW filter on device eth0
  1487. 35.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.467338] systemd-logind[649]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
  1488. 35.74 s [vm-test-run-centjes-e2e-test] client # [ 8.645681] AES CTR mode by8 optimization enabled
  1489. 35.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.879096] [drm] Found EDID data blob.
  1490. 35.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.501764] systemd-logind[649]: Watching system buttons on /dev/input/event2 (Power Button)
  1491. 35.75 s [vm-test-run-centjes-e2e-test] client # [ 8.251092] dhcpcd[738]: eth0: waiting for carrier[ 8.754051] [drm] Found bochs VGA, ID 0xb0c5.
  1492. 35.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.896710] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0
  1493. 35.76 s [vm-test-run-centjes-e2e-test] client # [ 8.755181] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.
  1494. 35.76 s [vm-test-run-centjes-e2e-test] client #
  1495. 35.76 s [vm-test-run-centjes-e2e-test] client # [ 8.371328] systemd[1]: Starting Virtual Console Setup...
  1496. 35.79 s [vm-test-run-centjes-e2e-test] client # [ 8.387726] systemd-logind[652]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
  1497. 35.82 s [vm-test-run-centjes-e2e-test] client # [ 8.422808] systemd[1]: Finished Permit User Sessions.
  1498. 35.83 s [vm-test-run-centjes-e2e-test] client # [ 8.435722] systemd[1]: Started Getty on tty1.
  1499. 35.83 s [vm-test-run-centjes-e2e-test] client # [ 8.443119] systemd[1]: Reached target Login Prompts.
  1500. 35.86 s [vm-test-run-centjes-e2e-test] client # [ 8.858731] [drm] Found EDID data blob.
  1501. 35.87 s [vm-test-run-centjes-e2e-test] client # [ 8.870059] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0
  1502. 35.88 s [vm-test-run-centjes-e2e-test] client # [ 8.493308] systemd-logind[652]: Watching system buttons on /dev/input/event2 (Power Button)
  1503. 36.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.102438] dhcpcd[752]: eth0: carrier acquired
  1504. 36.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.118408] dhcpcd[752]: DUID 00:01:00:01:31:82:27:38:52:54:00:12:34:56
  1505. 36.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.121938] dhcpcd[752]: eth0: IAID 00:12:34:56
  1506. 36.38 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.129140] dhcpcd[752]: eth0: adding address fe80::5054:ff:fe12:3456
  1507. 36.45 s [vm-test-run-centjes-e2e-test] client # [ 9.066354] dhcpcd[738]: eth0: carrier acquired
  1508. 36.47 s [vm-test-run-centjes-e2e-test] client # [ 9.078312] dhcpcd[738]: DUID 00:01:00:01:31:82:27:3a:52:54:00:12:34:56
  1509. 36.47 s [vm-test-run-centjes-e2e-test] client # [ 9.080549] dhcpcd[738]: eth0: IAID 00:12:34:56
  1510. 36.47 s [vm-test-run-centjes-e2e-test] client # [ 9.082471] dhcpcd[738]: eth0: adding address fe80::5054:ff:fe12:3456
  1511. 36.53 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.925110] fbcon: bochs-drmdrmfb (fb0) is primary device
  1512. 36.54 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.546716] Console: switching to colour frame buffer device 160x50
  1513. 36.54 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.684333] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device
  1514. 36.61 s [vm-test-run-centjes-e2e-test] client # [ 8.907075] fbcon: bochs-drmdrmfb (fb0) is primary device
  1515. 36.62 s [vm-test-run-centjes-e2e-test] client # [ 9.475286] ppdev: user-space parallel port driver
  1516. 36.62 s [vm-test-run-centjes-e2e-test] client # [ 9.489481] Console: switching to colour frame buffer device 160x50
  1517. 36.63 s [vm-test-run-centjes-e2e-test] client # [ 9.625032] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device
  1518. 36.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.877163] ppdev: user-space parallel port driver
  1519. 36.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.531350] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
  1520. 36.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.933082] kvm_amd: TSC scaling supported
  1521. 36.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.934205] kvm_amd: Nested Virtualization enabled
  1522. 36.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.935384] kvm_amd: Nested Paging enabled
  1523. 36.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.936395] kvm_amd: LBR virtualization supported
  1524. 36.81 s [vm-test-run-centjes-e2e-test] client # [ 9.413831] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
  1525. 36.82 s [vm-test-run-centjes-e2e-test] client # [ 9.821678] kvm_amd: TSC scaling supported
  1526. 36.82 s [vm-test-run-centjes-e2e-test] client # [ 9.822720] kvm_amd: Nested Virtualization enabled
  1527. 36.82 s [vm-test-run-centjes-e2e-test] client # [ 9.823853] kvm_amd: Nested Paging enabled
  1528. 36.83 s [vm-test-run-centjes-e2e-test] client # [ 9.824900] kvm_amd: LBR virtualization supported
  1529. 36.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.979835] kvm_amd: Virtual VMLOAD VMSAVE supported
  1530. 36.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.981188] kvm_amd: Virtual GIF supported
  1531. 36.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.606813] systemd-logind[649]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)
  1532. 36.87 s [vm-test-run-centjes-e2e-test] client # [ 9.872487] kvm_amd: Virtual VMLOAD VMSAVE supported
  1533. 36.87 s [vm-test-run-centjes-e2e-test] client # [ 9.873825] kvm_amd: Virtual GIF supported
  1534. 36.91 s [vm-test-run-centjes-e2e-test] client # [ 9.513780] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
  1535. 36.91 s [vm-test-run-centjes-e2e-test] client # [ 9.520972] systemd[1]: Stopped Virtual Console Setup.
  1536. 36.92 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.662599] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
  1537. 36.92 s [vm-test-run-centjes-e2e-test] client # [ 9.526913] systemd-logind[652]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)
  1538. 36.92 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.674532] systemd[1]: Stopped Virtual Console Setup.
  1539. 36.93 s [vm-test-run-centjes-e2e-test] client # [ 9.536575] systemd[1]: Starting Virtual Console Setup...
  1540. 36.93 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.679593] systemd[1]: Starting Virtual Console Setup...
  1541. 36.95 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.703807] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
  1542. 36.96 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.714620] systemd[1]: Stopped Virtual Console Setup.
  1543. 36.96 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.717380] systemd[1]: Starting Virtual Console Setup...
  1544. 36.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.120302] EDAC MC: Ver: 3.0.0
  1545. 37.00 s [vm-test-run-centjes-e2e-test] client # [ 9.601433] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
  1546. 37.07 s [vm-test-run-centjes-e2e-test] client # [ 9.612415] systemd[1]: Stopped Virtual Console Setup.
  1547. 37.07 s [vm-test-run-centjes-e2e-test] client # [ 10.003002] EDAC MC: Ver: 3.0.0
  1548. 37.07 s [vm-test-run-centjes-e2e-test] client # [ 9.618417] systemd[1]: Starting Virtual Console Setup...
  1549. 37.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.126164] systemd[1]: Finished Virtual Console Setup.
  1550. 37.41 s [vm-test-run-centjes-e2e-test] client # [ 10.019375] systemd[1]: Finished Virtual Console Setup.
  1551. 37.49 s [vm-test-run-centjes-e2e-test] client # [ 10.104793] dhcpcd[738]: eth0: soliciting a DHCP lease
  1552. 37.51 s [vm-test-run-centjes-e2e-test] client # [ 10.509918] NET: Registered PF_PACKET protocol family
  1553. 37.52 s [vm-test-run-centjes-e2e-test] client # [ 10.131724] dhcpcd[738]: eth0: offered 10.0.2.15 from 10.0.2.2
  1554. 37.52 s [vm-test-run-centjes-e2e-test] client # [ 10.135338] dhcpcd[738]: eth0: probing address 10.0.2.15/24
  1555. 37.57 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.324780] dhcpcd[752]: eth0: soliciting a DHCP lease
  1556. 37.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.732030] NET: Registered PF_PACKET protocol family
  1557. 37.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.354713] dhcpcd[752]: eth0: offered 10.0.2.15 from 10.0.2.2
  1558. 37.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.358351] dhcpcd[752]: eth0: probing address 10.0.2.15/24
  1559. 37.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.720099] dhcpcd[752]: eth0: soliciting an IPv6 router
  1560. 37.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.722545] dhcpcd[752]: eth0: Router Advertisement from fe80::2
  1561. 37.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.724523] dhcpcd[752]: eth0: adding address fec0::5054:ff:fe12:3456/64
  1562. 37.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.726751] dhcpcd[752]: eth0: adding route to fec0::/64
  1563. 37.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.728653] dhcpcd[752]: eth0: adding default route via fe80::2
  1564. 38.54 s [vm-test-run-centjes-e2e-test] client # [ 11.153247] dhcpcd[738]: eth0: soliciting an IPv6 router
  1565. 38.54 s [vm-test-run-centjes-e2e-test] client # [ 11.155306] dhcpcd[738]: eth0: Router Advertisement from fe80::2
  1566. 38.55 s [vm-test-run-centjes-e2e-test] client # [ 11.157360] dhcpcd[738]: eth0: adding address fec0::5054:ff:fe12:3456/64
  1567. 38.55 s [vm-test-run-centjes-e2e-test] client # [ 11.159463] dhcpcd[738]: eth0: adding route to fec0::/64
  1568. 38.55 s [vm-test-run-centjes-e2e-test] client # [ 11.161439] dhcpcd[738]: eth0: adding default route via fe80::2
  1569. 42.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 15.080469] dhcpcd[752]: eth0: leased 10.0.2.15 for 86400 seconds
  1570. 42.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 15.082812] dhcpcd[752]: eth0: adding route to 10.0.2.0/24
  1571. 42.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 15.084875] dhcpcd[752]: eth0: adding default route via 10.0.2.2
  1572. 42.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 15.193586] systemd[1]: Started DHCP Client.
  1573. 42.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 15.198455] systemd[1]: Reached target Multi-User System.
  1574. 42.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 15.200328] systemd[1]: Startup finished in 3.440s (kernel) + 11.756s (userspace) = 15.196s.
  1575. 43.02 s [vm-test-run-centjes-e2e-test] client # [ 15.627633] dhcpcd[738]: eth0: leased 10.0.2.15 for 86400 seconds
  1576. 43.02 s [vm-test-run-centjes-e2e-test] client # [ 15.629894] dhcpcd[738]: eth0: adding route to 10.0.2.0/24
  1577. 43.02 s [vm-test-run-centjes-e2e-test] client # [ 15.632095] dhcpcd[738]: eth0: adding default route via 10.0.2.2
  1578. 43.13 s [vm-test-run-centjes-e2e-test] client # [ 15.743305] systemd[1]: Started DHCP Client.
  1579. 43.14 s [vm-test-run-centjes-e2e-test] client # [ 15.746701] systemd[1]: Reached target Multi-User System.
  1580. 43.14 s [vm-test-run-centjes-e2e-test] client # [ 15.749868] systemd[1]: Startup finished in 3.472s (kernel) + 12.275s (userspace) = 15.748s.
  1581. 43.27 s [vm-test-run-centjes-e2e-test] docsserver: (finished: waiting for unit default.target, in 17.03 seconds)
  1582. 43.27 s [vm-test-run-centjes-e2e-test] client: waiting for unit default.target
  1583. 43.27 s [vm-test-run-centjes-e2e-test] client: waiting for the VM to finish booting
  1584. 43.27 s [vm-test-run-centjes-e2e-test] client: Guest shell says: b'Spawning backdoor root shell...\n'
  1585. 43.27 s [vm-test-run-centjes-e2e-test] client: connected to guest root shell
  1586. 43.27 s [vm-test-run-centjes-e2e-test] client: (connecting took 0.00 seconds)
  1587. 43.27 s [vm-test-run-centjes-e2e-test] client: (finished: waiting for the VM to finish booting, in 0.00 seconds)
  1588. 43.39 s [vm-test-run-centjes-e2e-test] client: (finished: waiting for unit default.target, in 0.12 seconds)
  1589. 43.46 s [vm-test-run-centjes-e2e-test] docsserver: waiting for unit centjes-docs-site-production.service
  1590. 43.50 s [vm-test-run-centjes-e2e-test] docsserver: (finished: waiting for unit centjes-docs-site-production.service, in 0.05 seconds)
  1591. 43.50 s [vm-test-run-centjes-e2e-test] docsserver: waiting for unit default.target
  1592. 43.55 s [vm-test-run-centjes-e2e-test] docsserver: (finished: waiting for unit default.target, in 0.05 seconds)
  1593. 43.55 s [vm-test-run-centjes-e2e-test] docsserver: waiting for TCP port 8001 on localhost
  1594. 43.62 s [vm-test-run-centjes-e2e-test] docsserver # Connection to localhost (127.0.0.1) 8001 port [tcp/vcom-tunnel] succeeded!
  1595. 43.62 s [vm-test-run-centjes-e2e-test] docsserver: (finished: waiting for TCP port 8001 on localhost, in 0.07 seconds)
  1596. 43.62 s [vm-test-run-centjes-e2e-test] client: must succeed: curl docsserver:8001
  1597. 43.71 s [vm-test-run-centjes-e2e-test] client # % Total % Received % Xferd Average Speed Time Time Time Current
  1598. 43.73 s [vm-test-run-centjes-e2e-test] client # Dload Upload Total Spent Left Speed
  1599. 43.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 16.503521] centjes-docs-site-production-start[635]: 192.168.1.1 - - [27/Apr/2026:13:55:12 +0000] "GET / HTTP/1.1" 200 3595 "" "curl/8.17.0"
  1600. 43.77 s [vm-test-run-centjes-e2e-test] client # 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0100 3595 100 3595 0 0 61240 0 --:--:-- --:--:-- --:--:-- 63070
  1601. 43.77 s [vm-test-run-centjes-e2e-test] client: (finished: must succeed: curl docsserver:8001, in 0.15 seconds)
  1602. 43.77 s [vm-test-run-centjes-e2e-test] (finished: run the VM test script, in 17.81 seconds)
  1603. 43.91 s [vm-test-run-centjes-e2e-test] test script finished in 17.95s
  1604. 43.91 s [vm-test-run-centjes-e2e-test] cleanup
  1605. 43.91 s [vm-test-run-centjes-e2e-test] kill machine (pid 31)
  1606. 43.91 s [vm-test-run-centjes-e2e-test] qemu-system-x86_64: terminating on signal 15 from pid 6 (/nix/store/jj6jldlw37r8yy9kc1smrax9dhnjm2x4-python3-3.13.9/bin/python3.13)
  1607. 43.92 s [vm-test-run-centjes-e2e-test] kill machine (pid 9)
  1608. 43.92 s [vm-test-run-centjes-e2e-test] qemu-system-x86_64: terminating on signal 15 from pid 6 (/nix/store/jj6jldlw37r8yy9kc1smrax9dhnjm2x4-python3-3.13.9/bin/python3.13)
  1609. 43.94 s [vm-test-run-centjes-e2e-test] kill vlan (pid 7)
  1610. 43.94 s [vm-test-run-centjes-e2e-test] (finished: cleanup, in 0.03 seconds)
  1611. 44.03 s [post-build-hook] Uploading paths from nix-ci to cachix cache "centjes" /nix/store/1611s7j372lwqw5b36c5v56df4ya6bca-vm-test-run-centjes-e2e-test
  1612. 44.41 s [post-build-hook] Pushing 1 paths (0 are already present) using zstd to cache centjes ⏳
  1613. 44.41 s [post-build-hook]
  1614. 44.83 s [post-build-hook] Pushing /nix/store/1611s7j372lwqw5b36c5v56df4ya6bca-vm-test-run-centjes-e2e-test (96.00 B)
  1615. 45.95 s [post-build-hook]
  1616. 45.95 s [post-build-hook] All done.
  1617. 45.96 s [post-build-hook] Uploading paths from nix-ci to the NixCI staging cache /nix/store/1611s7j372lwqw5b36c5v56df4ya6bca-vm-test-run-centjes-e2e-test
  1618. 46.00 s [post-build-hook] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
  1619. 46.00 s [post-build-hook] copying 1 paths...
  1620. 46.00 s [post-build-hook] copying path '/nix/store/1611s7j372lwqw5b36c5v56df4ya6bca-vm-test-run-centjes-e2e-test' to 'https://cache.staging.nix-ci.com'...
  1621. 46.10 s [post-build-hook] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
  1622. 46.39 s [post-build-hook] copying 1 paths...
  1623. 46.39 s [post-build-hook] copying path '/nix/store/5l7rsrwmqp06f0b3n39irva97zdcvawh-vm-test-run-centjes-e2e-test.drv' to 'https://cache.staging.nix-ci.com'...
  1624. 46.58 s Progress: 7 of 8 built
More Logs Raw Logs

Agent Instructions

Wait for completion, without logs:

curl --netrc -N https://staging.nix-ci.com/gh:NorfairKing:centjes/refs%2Fpull%2F2%2Fmerge/d826b4fcc9fd37022acc53b94edda6d94580d29b/050ef7bf-1f0c-4175-9f0b-0d90767b4b33/wait

Latest logs, with timestamps (streams while running):

curl --netrc -N https://staging.nix-ci.com/gh:NorfairKing:centjes/refs%2Fpull%2F2%2Fmerge/d826b4fcc9fd37022acc53b94edda6d94580d29b/050ef7bf-1f0c-4175-9f0b-0d90767b4b33/tail

All logs, with timestamps (available after completion):

curl --netrc https://staging.nix-ci.com/gh:NorfairKing:centjes/refs%2Fpull%2F2%2Fmerge/d826b4fcc9fd37022acc53b94edda6d94580d29b/050ef7bf-1f0c-4175-9f0b-0d90767b4b33/logs

Raw logs, from the start (no timestamps):

curl --netrc -N https://staging.nix-ci.com/gh:NorfairKing:centjes/refs%2Fpull%2F2%2Fmerge/d826b4fcc9fd37022acc53b94edda6d94580d29b/050ef7bf-1f0c-4175-9f0b-0d90767b4b33/raw-logs

Content types available for /logs and /tail (use Accept header):

Example:

curl --netrc -H "Accept: text/csv" https://staging.nix-ci.com/gh:NorfairKing:centjes/refs%2Fpull%2F2%2Fmerge/d826b4fcc9fd37022acc53b94edda6d94580d29b/050ef7bf-1f0c-4175-9f0b-0d90767b4b33/logs

Reproduce locally: https://staging.nix-ci.com/gh:NorfairKing:centjes/refs%2Fpull%2F2%2Fmerge/d826b4fcc9fd37022acc53b94edda6d94580d29b/050ef7bf-1f0c-4175-9f0b-0d90767b4b33/repro

For more instructions, fetch https://staging.nix-ci.com/instructions .