build checks.x86_64-linux.e2e-test

Queued Started Finished

Reproduce this run
  1. 0.50 s $ /nix/store/9ypz3flqsrl5xl495mm8h645gadjsxi1-coreutils-9.11/bin/timeout --kill-after=15s 7200s /nix/store/23zk8sgzamrbmj1fday15szf7f2279s8-nix-2.34.7/bin/nix --extra-experimental-features nix-command --extra-experimental-features flakes --log-format internal-json build --no-link --print-out-paths git+https://github.com/NorfairKing/centjes?ref=master&rev=1687d2a96d1c0f4c6369882d6ee7b6e1bd10a2ff&shallow=1#checks.x86_64-linux.e2e-test --print-build-logs
  2. 0.82 s warning: ignoring untrusted flake configuration setting 'extra-substituters'.
  3. 0.82 s Pass '--accept-flake-config' to trust it
  4. 0.82 s warning: ignoring untrusted flake configuration setting 'extra-trusted-public-keys'.
  5. 0.82 s Pass '--accept-flake-config' to trust it
  6. 0.82 s error (ignored): SQLite database '/var/cache/nix-ci-worker/eval-cache-v6/bdbf4cb6960fdd487c47c861940be2a2df0febc52d4639eca8f608b7287164d2.sqlite' is busy
  7. 8.63 s
  8. 9.37 s Downloading cached settings-check from https://cache.staging.nix-ci.com
  9. 9.37 s Downloading cached centjes-docs-site from https://cache.staging.nix-ci.com
  10. 9.39 s Downloaded cached settings-check (144 B) in 26ms
  11. 9.39 s Progress: 0 of 2 built, 1 of 11 downloaded from cache (1 downloading)
  12. 9.56 s Downloaded cached centjes-docs-site (9.4 MiB) in 197ms
  13. 9.56 s Progress: 0 of 2 built, 2 of 11 downloaded from cache
  14. 9.56 s Downloading cached unit-script-centjes-docs-site-production-start from https://cache.staging.nix-ci.com
  15. 9.59 s Downloaded cached unit-script-centjes-docs-site-production-start (680 B) in 20ms
  16. 9.59 s Progress: 0 of 2 built, 3 of 11 downloaded from cache
  17. 9.59 s Downloading cached unit-centjes-docs-site-production.service from https://cache.staging.nix-ci.com
  18. 9.62 s Downloaded cached unit-centjes-docs-site-production.service (1664 B) in 31ms
  19. 9.62 s Progress: 0 of 2 built, 4 of 11 downloaded from cache
  20. 9.62 s Downloading cached system-units from https://cache.staging.nix-ci.com
  21. 9.68 s Downloaded cached system-units (96 KiB) in 52ms
  22. 9.68 s Progress: 0 of 2 built, 5 of 11 downloaded from cache
  23. 9.68 s Downloading cached etc from https://cache.staging.nix-ci.com
  24. 9.72 s Downloaded cached etc (31 KiB) in 36ms
  25. 9.72 s Progress: 0 of 2 built, 6 of 11 downloaded from cache
  26. 9.72 s Downloading cached nixos-system-docsserver-test from https://cache.staging.nix-ci.com
  27. 9.74 s Downloaded cached nixos-system-docsserver-test (17 KiB) in 20ms
  28. 9.74 s Progress: 0 of 2 built, 7 of 11 downloaded from cache
  29. 9.75 s Downloading cached closure-info from https://cache.staging.nix-ci.com
  30. 9.79 s Downloaded cached closure-info (228 KiB) in 45ms
  31. 9.79 s Progress: 0 of 2 built, 8 of 11 downloaded from cache
  32. 9.79 s Downloading cached run-nixos-vm from https://cache.staging.nix-ci.com
  33. 9.81 s Downloaded cached run-nixos-vm (3008 B) in 17ms
  34. 9.81 s Progress: 0 of 2 built, 9 of 11 downloaded from cache
  35. 9.81 s Downloading cached nixos-vm from https://cache.staging.nix-ci.com
  36. 9.83 s Downloaded cached nixos-vm (776 B) in 17ms
  37. 9.83 s Progress: 0 of 2 built, 10 of 11 downloaded from cache
  38. 9.83 s Downloading cached nixos-test-driver-centjes-e2e-test from https://cache.staging.nix-ci.com
  39. 9.85 s Downloaded cached nixos-test-driver-centjes-e2e-test (1888 B) in 17ms
  40. 9.85 s Progress: 0 of 2 built, 11 of 11 downloaded from cache
  41. 10.13 s Building /nix/store/4bnglafmv1lk2xqpzyn79ymn2z4xwcpp-vm-test-run-centjes-e2e-test.drv
  42. 11.37 s [vm-test-run-centjes-e2e-test] Machine state will be reset. To keep it, pass --keep-vm-state
  43. 11.37 s [vm-test-run-centjes-e2e-test] start all VLans
  44. 11.37 s [vm-test-run-centjes-e2e-test] start vlan
  45. 11.38 s [vm-test-run-centjes-e2e-test] running vlan (pid 7; ctl /build/vde1.ctl)
  46. 11.38 s [vm-test-run-centjes-e2e-test] (finished: start all VLans, in 0.01 seconds)
  47. 11.38 s [vm-test-run-centjes-e2e-test] Test will time out and terminate in 3600 seconds
  48. 11.38 s [vm-test-run-centjes-e2e-test] run the VM test script
  49. 11.39 s [vm-test-run-centjes-e2e-test] additionally exposed symbols:
  50. 11.39 s [vm-test-run-centjes-e2e-test] client, docsserver,
  51. 11.39 s [vm-test-run-centjes-e2e-test] vlan1,
  52. 11.39 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
  53. 11.39 s [vm-test-run-centjes-e2e-test] docsserver: starting vm
  54. 11.48 s [vm-test-run-centjes-e2e-test] mke2fs 1.47.3 (8-Jul-2025)
  55. 11.83 s [vm-test-run-centjes-e2e-test] docsserver # Disk image does not exist, creating the virtualisation disk image...
  56. 11.83 s [vm-test-run-centjes-e2e-test] docsserver # Formatting '/build/vm-state-docsserver/tmp.wlkr6HEfKN', fmt=raw size=1073741824
  57. 11.83 s [vm-test-run-centjes-e2e-test] docsserver # Discarding device blocks: 0/262144 done
  58. 11.83 s [vm-test-run-centjes-e2e-test] docsserver # Creating filesystem with 262144 4k blocks and 65536 inodes
  59. 11.83 s [vm-test-run-centjes-e2e-test] docsserver # Filesystem UUID: 3a328118-91e8-468d-8e77-5e707df778c4
  60. 11.83 s [vm-test-run-centjes-e2e-test] docsserver # Superblock backups stored on blocks:
  61. 11.83 s [vm-test-run-centjes-e2e-test] docsserver # 32768, 98304, 163840, 229376
  62. 11.83 s [vm-test-run-centjes-e2e-test] docsserver #
  63. 11.83 s [vm-test-run-centjes-e2e-test] docsserver # Allocating group tables: 0/8 done
  64. 11.83 s [vm-test-run-centjes-e2e-test] docsserver: QEMU running (pid 9)
  65. 11.83 s [vm-test-run-centjes-e2e-test] docsserver # Writing inode tables: 0/8 done
  66. 11.83 s [vm-test-run-centjes-e2e-test] docsserver # Creating journal (8192 blocks): done
  67. 11.83 s [vm-test-run-centjes-e2e-test] docsserver # Writing superblocks and filesystem accounting information: 0/8 done
  68. 11.83 s [vm-test-run-centjes-e2e-test] client: starting vm
  69. 11.83 s [vm-test-run-centjes-e2e-test] docsserver #
  70. 11.83 s [vm-test-run-centjes-e2e-test] docsserver # Virtualisation disk image created.
  71. 11.87 s [vm-test-run-centjes-e2e-test] mke2fs 1.47.3 (8-Jul-2025)
  72. 11.94 s [vm-test-run-centjes-e2e-test] docsserver # c[?7lSeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)
  73. 12.05 s [vm-test-run-centjes-e2e-test] docsserver #
  74. 12.05 s [vm-test-run-centjes-e2e-test] docsserver #
  75. 12.06 s [vm-test-run-centjes-e2e-test] docsserver # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+3EFD1920+3EF31920 CA00
  76. 12.07 s [vm-test-run-centjes-e2e-test] docsserver # Press Ctrl-B to configure iPXE (PCI 00:03.0)...
  77. 12.07 s [vm-test-run-centjes-e2e-test] docsserver #
  78. 12.07 s [vm-test-run-centjes-e2e-test] docsserver #
  79. 12.07 s [vm-test-run-centjes-e2e-test] docsserver #
  80. 12.07 s [vm-test-run-centjes-e2e-test] docsserver #
  81. 12.08 s [vm-test-run-centjes-e2e-test] docsserver # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM 3EFD1920 3EF31920 CB00
  82. 12.10 s [vm-test-run-centjes-e2e-test] docsserver # Press Ctrl-B to configure iPXE (PCI 00:09.0)...
  83. 12.10 s [vm-test-run-centjes-e2e-test] docsserver #
  84. 12.10 s [vm-test-run-centjes-e2e-test] docsserver #
  85. 12.11 s [vm-test-run-centjes-e2e-test] client # Disk image does not exist, creating the virtualisation disk image...
  86. 12.11 s [vm-test-run-centjes-e2e-test] client # Formatting '/build/vm-state-client/tmp.nD6aMDzDCn', fmt=raw size=1073741824
  87. 12.11 s [vm-test-run-centjes-e2e-test] client # Discarding device blocks: 0/262144 done
  88. 12.11 s [vm-test-run-centjes-e2e-test] client: QEMU running (pid 31)
  89. 12.12 s [vm-test-run-centjes-e2e-test] client # Creating filesystem with 262144 4k blocks and 65536 inodes
  90. 12.12 s [vm-test-run-centjes-e2e-test] client # Filesystem UUID: fbe26b78-2ebe-4421-88bf-5b0b2fc8302f
  91. 12.12 s [vm-test-run-centjes-e2e-test] client # Superblock backups stored on blocks:
  92. 12.12 s [vm-test-run-centjes-e2e-test] client # 32768, 98304, 163840, 229376
  93. 12.12 s [vm-test-run-centjes-e2e-test] client #
  94. 12.12 s [vm-test-run-centjes-e2e-test] docsserver: waiting for unit default.target
  95. 12.12 s [vm-test-run-centjes-e2e-test] client # Allocating group tables: 0/8 done
  96. 12.12 s [vm-test-run-centjes-e2e-test] docsserver: waiting for the VM to finish booting
  97. 12.12 s [vm-test-run-centjes-e2e-test] client # Writing inode tables: 0/8 done
  98. 12.12 s [vm-test-run-centjes-e2e-test] client # Creating journal (8192 blocks): done
  99. 12.12 s [vm-test-run-centjes-e2e-test] client # Writing superblocks and filesystem accounting information: 0/8 done
  100. 12.12 s [vm-test-run-centjes-e2e-test] client #
  101. 12.12 s [vm-test-run-centjes-e2e-test] client # Virtualisation disk image created.
  102. 12.14 s [vm-test-run-centjes-e2e-test] docsserver # Booting from ROM...
  103. 12.16 s [vm-test-run-centjes-e2e-test] docsserver # Probing EDD (edd=off to disable)... ok
  104. 12.29 s [vm-test-run-centjes-e2e-test] client # c[?7lSeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)
  105. 12.36 s [vm-test-run-centjes-e2e-test] client #
  106. 12.36 s [vm-test-run-centjes-e2e-test] client #
  107. 12.37 s [vm-test-run-centjes-e2e-test] client # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+3EFD1920+3EF31920 CA00
  108. 12.40 s [vm-test-run-centjes-e2e-test] client # Press Ctrl-B to configure iPXE (PCI 00:03.0)...
  109. 12.40 s [vm-test-run-centjes-e2e-test] client #
  110. 12.40 s [vm-test-run-centjes-e2e-test] client #
  111. 12.40 s [vm-test-run-centjes-e2e-test] client #
  112. 12.40 s [vm-test-run-centjes-e2e-test] client #
  113. 12.41 s [vm-test-run-centjes-e2e-test] client # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM 3EFD1920 3EF31920 CB00
  114. 12.44 s [vm-test-run-centjes-e2e-test] client # Press Ctrl-B to configure iPXE (PCI 00:09.0)...
  115. 12.44 s [vm-test-run-centjes-e2e-test] client #
  116. 12.44 s [vm-test-run-centjes-e2e-test] client #
  117. 12.49 s [vm-test-run-centjes-e2e-test] client # Booting from ROM...
  118. 12.51 s [vm-test-run-centjes-e2e-test] client # Probing EDD (edd=off to disable)... ok
  119. 12.62 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
  120. 12.63 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/s2hzr4dfcc5kw5va4694x014sn949jw6-nixos-system-docsserver-test/init regInfo=/nix/store/s09rasgqf8zga62zkbwfj26bip0j8kw2-closure-info/registration console=ttyS0,115200n8 console=tty0
  121. 12.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-provided physical RAM map:
  122. 12.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
  123. 12.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
  124. 12.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
  125. 12.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffdafff] usable
  126. 12.65 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x000000003ffdb000-0x000000003fffffff] reserved
  127. 12.65 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
  128. 12.65 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
  129. 12.65 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved
  130. 12.65 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] NX (Execute Disable) protection: active
  131. 12.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] APIC: Static calls initialized
  132. 12.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] SMBIOS 2.8 present.
  133. 12.66 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
  134. 12.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] DMI: Memory slots populated: 1/1
  135. 12.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] Hypervisor detected: KVM
  136. 12.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000
  137. 12.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
  138. 12.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000001] kvm-clock: using sched offset of 608256859 cycles
  139. 12.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000003] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
  140. 12.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000011] tsc: Detected 3399.998 MHz processor
  141. 12.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000956] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000
  142. 12.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.001212] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs
  143. 12.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.001231] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT
  144. 12.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003473] found SMP MP-table at [mem 0x000f5470-0x000f547f]
  145. 12.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003528] Using GB pages for direct mapping
  146. 12.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003623] RAMDISK: [mem 0x3f3cd000-0x3ffcffff]
  147. 12.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003685] ACPI: Early table checksum verification disabled
  148. 12.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003693] ACPI: RSDP 0x00000000000F5290 000014 (v00 BOCHS )
  149. 12.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003698] ACPI: RSDT 0x000000003FFE23D9 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  150. 12.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003707] ACPI: FACP 0x000000003FFE228D 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  151. 12.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003714] ACPI: DSDT 0x000000003FFE0040 00224D (v01 BOCHS BXPC 00000001 BXPC 00000001)
  152. 12.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003717] ACPI: FACS 0x000000003FFE0000 000040
  153. 12.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003720] ACPI: APIC 0x000000003FFE2301 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)
  154. 12.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003722] ACPI: HPET 0x000000003FFE2379 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  155. 12.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003723] ACPI: WAET 0x000000003FFE23B1 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  156. 12.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003725] ACPI: Reserving FACP table memory at [mem 0x3ffe228d-0x3ffe2300]
  157. 12.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003727] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe228c]
  158. 12.71 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003728] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]
  159. 12.71 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003729] ACPI: Reserving APIC table memory at [mem 0x3ffe2301-0x3ffe2378]
  160. 12.71 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003730] ACPI: Reserving HPET table memory at [mem 0x3ffe2379-0x3ffe23b0]
  161. 12.71 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003731] ACPI: Reserving WAET table memory at [mem 0x3ffe23b1-0x3ffe23d8]
  162. 12.71 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.004596] No NUMA configuration found
  163. 12.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.004598] Faking a node at [mem 0x0000000000000000-0x000000003ffdafff]
  164. 12.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.004606] NODE_DATA(0) allocated [mem 0x3ffd58c0-0x3ffdadff]
  165. 12.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.004686] Zone ranges:
  166. 12.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.004687] DMA [mem 0x0000000000001000-0x0000000000ffffff]
  167. 12.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.004689] DMA32 [mem 0x0000000001000000-0x000000003ffdafff]
  168. 12.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.004690] Normal empty
  169. 12.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.004692] Device empty
  170. 12.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.004692] Movable zone start for each node
  171. 12.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.004693] Early memory node ranges
  172. 12.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.004694] node 0: [mem 0x0000000000001000-0x000000000009efff]
  173. 12.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.004696] node 0: [mem 0x0000000000100000-0x000000003ffdafff]
  174. 12.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.004697] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdafff]
  175. 12.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.005260] On node 0, zone DMA: 1 pages in unavailable ranges
  176. 12.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.005279] On node 0, zone DMA: 97 pages in unavailable ranges
  177. 12.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.009426] On node 0, zone DMA32: 37 pages in unavailable ranges
  178. 12.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.010916] ACPI: PM-Timer IO Port: 0x608
  179. 12.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.010954] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
  180. 12.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011072] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
  181. 12.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011077] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
  182. 12.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011079] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
  183. 12.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011081] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
  184. 12.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011083] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
  185. 12.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011084] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
  186. 12.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011087] ACPI: Using ACPI (MADT) for SMP configuration information
  187. 12.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011091] ACPI: HPET id: 0x8086a201 base: 0xfed00000
  188. 12.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011103] TSC deadline timer available
  189. 12.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011125] CPU topo: Max. logical packages: 1
  190. 12.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011127] CPU topo: Max. logical dies: 1
  191. 12.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011128] CPU topo: Max. dies per package: 1
  192. 12.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011150] CPU topo: Max. threads per core: 1
  193. 12.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011151] CPU topo: Num. cores per package: 1
  194. 12.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011153] CPU topo: Num. threads per package: 1
  195. 12.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011153] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs
  196. 12.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011206] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()
  197. 12.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011294] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]
  198. 12.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011296] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]
  199. 12.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011299] [mem 0x40000000-0xfeffbfff] available for PCI devices
  200. 12.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011300] Booting paravirtualized kernel on KVM
  201. 12.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011309] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
  202. 12.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.015776] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1
  203. 12.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.018082] percpu: Embedded 116 pages/cpu s294912 r65536 d114688 u2097152
  204. 12.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.018192] kvm-guest: PV spinlocks disabled, single CPU
  205. 12.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.018195] 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/s2hzr4dfcc5kw5va4694x014sn949jw6-nixos-system-docsserver-test/init regInfo=/nix/store/s09rasgqf8zga62zkbwfj26bip0j8kw2-closure-info/registration console=ttyS0,115200n8 console=tty0
  206. 12.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.018311] Unknown kernel command line parameters "regInfo=/nix/store/s09rasgqf8zga62zkbwfj26bip0j8kw2-closure-info/registration", will be passed to user space.
  207. 12.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.018332] random: crng init done
  208. 12.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.018535] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
  209. 12.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.018677] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
  210. 12.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.018743] Fallback order for Node 0: 0
  211. 12.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.018748] Built 1 zonelists, mobility grouping on. Total pages: 262009
  212. 12.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.018749] Policy zone: DMA32
  213. 12.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.019364] mem auto-init: stack:all(zero), heap alloc:on, heap free:off
  214. 12.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.027498] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
  215. 12.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.028953] allocated 2097152 bytes of page_ext
  216. 12.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.029003] ftrace: allocating 46208 entries in 181 pages
  217. 12.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.047077] ftrace: allocated 181 pages with 5 groups
  218. 12.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.049174] Dynamic Preempt: voluntary
  219. 12.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.050369] rcu: Preemptible hierarchical RCU implementation.
  220. 12.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.050373] rcu: RCU event tracing is enabled.
  221. 12.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.050375] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.
  222. 12.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.050380] Trampoline variant of Tasks RCU enabled.
  223. 12.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.050381] Rude variant of Tasks RCU enabled.
  224. 12.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.050382] Tracing variant of Tasks RCU enabled.
  225. 12.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.050384] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
  226. 12.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.050386] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
  227. 12.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.050412] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
  228. 12.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.050417] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
  229. 12.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.050421] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
  230. 12.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.057356] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16
  231. 12.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.057704] rcu: srcu_init: Setting srcu_struct sizes based on contention.
  232. 12.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.057893] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)
  233. 12.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.070128] Console: colour VGA+ 80x25
  234. 12.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.070144] printk: legacy console [tty0] enabled
  235. 12.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.177058] printk: legacy console [ttyS0] enabled
  236. 12.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.407826] ACPI: Core revision 20240827
  237. 12.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.410187] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
  238. 12.93 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
  239. 12.94 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
  240. 12.94 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-provided physical RAM map:
  241. 12.94 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
  242. 12.94 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
  243. 12.94 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
  244. 12.95 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffdafff] usable
  245. 12.95 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x000000003ffdb000-0x000000003fffffff] reserved
  246. 12.95 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
  247. 12.95 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
  248. 12.96 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved
  249. 12.96 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] NX (Execute Disable) protection: active
  250. 12.96 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] APIC: Static calls initialized
  251. 12.96 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] SMBIOS 2.8 present.
  252. 12.96 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
  253. 12.96 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] DMI: Memory slots populated: 1/1
  254. 12.97 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] Hypervisor detected: KVM
  255. 12.97 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000
  256. 12.97 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
  257. 12.97 s [vm-test-run-centjes-e2e-test] client # [ 0.000001] kvm-clock: using sched offset of 639668268 cycles
  258. 12.98 s [vm-test-run-centjes-e2e-test] client # [ 0.000003] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
  259. 12.98 s [vm-test-run-centjes-e2e-test] client # [ 0.000008] tsc: Detected 3399.998 MHz processor
  260. 12.98 s [vm-test-run-centjes-e2e-test] client # [ 0.000790] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000
  261. 12.98 s [vm-test-run-centjes-e2e-test] client # [ 0.001001] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs
  262. 12.98 s [vm-test-run-centjes-e2e-test] client # [ 0.001011] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT
  263. 12.99 s [vm-test-run-centjes-e2e-test] client # [ 0.002903] found SMP MP-table at [mem 0x000f5470-0x000f547f]
  264. 12.99 s [vm-test-run-centjes-e2e-test] client # [ 0.002944] Using GB pages for direct mapping
  265. 12.99 s [vm-test-run-centjes-e2e-test] client # [ 0.003007] RAMDISK: [mem 0x3f3cd000-0x3ffcffff]
  266. 12.99 s [vm-test-run-centjes-e2e-test] client # [ 0.003072] ACPI: Early table checksum verification disabled
  267. 12.99 s [vm-test-run-centjes-e2e-test] client # [ 0.003077] ACPI: RSDP 0x00000000000F5290 000014 (v00 BOCHS )
  268. 13.00 s [vm-test-run-centjes-e2e-test] client # [ 0.003081] ACPI: RSDT 0x000000003FFE23D9 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  269. 13.00 s [vm-test-run-centjes-e2e-test] client # [ 0.003088] ACPI: FACP 0x000000003FFE228D 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  270. 13.00 s [vm-test-run-centjes-e2e-test] client # [ 0.003094] ACPI: DSDT 0x000000003FFE0040 00224D (v01 BOCHS BXPC 00000001 BXPC 00000001)
  271. 13.00 s [vm-test-run-centjes-e2e-test] client # [ 0.003097] ACPI: FACS 0x000000003FFE0000 000040
  272. 13.01 s [vm-test-run-centjes-e2e-test] client # [ 0.003098] ACPI: APIC 0x000000003FFE2301 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)
  273. 13.01 s [vm-test-run-centjes-e2e-test] client # [ 0.003100] ACPI: HPET 0x000000003FFE2379 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  274. 13.01 s [vm-test-run-centjes-e2e-test] client # [ 0.003101] ACPI: WAET 0x000000003FFE23B1 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  275. 13.01 s [vm-test-run-centjes-e2e-test] client # [ 0.003103] ACPI: Reserving FACP table memory at [mem 0x3ffe228d-0x3ffe2300]
  276. 13.02 s [vm-test-run-centjes-e2e-test] client # [ 0.003104] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe228c]
  277. 13.02 s [vm-test-run-centjes-e2e-test] client # [ 0.003104] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]
  278. 13.02 s [vm-test-run-centjes-e2e-test] client # [ 0.003105] ACPI: Reserving APIC table memory at [mem 0x3ffe2301-0x3ffe2378]
  279. 13.02 s [vm-test-run-centjes-e2e-test] client # [ 0.003106] ACPI: Reserving HPET table memory at [mem 0x3ffe2379-0x3ffe23b0]
  280. 13.03 s [vm-test-run-centjes-e2e-test] client # [ 0.003106] ACPI: Reserving WAET table memory at [mem 0x3ffe23b1-0x3ffe23d8]
  281. 13.03 s [vm-test-run-centjes-e2e-test] client # [ 0.003934] No NUMA configuration found
  282. 13.03 s [vm-test-run-centjes-e2e-test] client # [ 0.003936] Faking a node at [mem 0x0000000000000000-0x000000003ffdafff]
  283. 13.03 s [vm-test-run-centjes-e2e-test] client # [ 0.003939] NODE_DATA(0) allocated [mem 0x3ffd58c0-0x3ffdadff]
  284. 13.03 s [vm-test-run-centjes-e2e-test] client # [ 0.003988] Zone ranges:
  285. 13.03 s [vm-test-run-centjes-e2e-test] client # [ 0.003989] DMA [mem 0x0000000000001000-0x0000000000ffffff]
  286. 13.04 s [vm-test-run-centjes-e2e-test] client # [ 0.003990] DMA32 [mem 0x0000000001000000-0x000000003ffdafff]
  287. 13.04 s [vm-test-run-centjes-e2e-test] client # [ 0.003991] Normal empty
  288. 13.04 s [vm-test-run-centjes-e2e-test] client # [ 0.003992] Device empty
  289. 13.04 s [vm-test-run-centjes-e2e-test] client # [ 0.003992] Movable zone start for each node
  290. 13.04 s [vm-test-run-centjes-e2e-test] client # [ 0.003993] Early memory node ranges
  291. 13.04 s [vm-test-run-centjes-e2e-test] client # [ 0.003993] node 0: [mem 0x0000000000001000-0x000000000009efff]
  292. 13.05 s [vm-test-run-centjes-e2e-test] client # [ 0.003995] node 0: [mem 0x0000000000100000-0x000000003ffdafff]
  293. 13.05 s [vm-test-run-centjes-e2e-test] client # [ 0.003996] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdafff]
  294. 13.05 s [vm-test-run-centjes-e2e-test] client # [ 0.004437] On node 0, zone DMA: 1 pages in unavailable ranges
  295. 13.05 s [vm-test-run-centjes-e2e-test] client # [ 0.004456] On node 0, zone DMA: 97 pages in unavailable ranges
  296. 13.05 s [vm-test-run-centjes-e2e-test] client # [ 0.012074] On node 0, zone DMA32: 37 pages in unavailable ranges
  297. 13.05 s [vm-test-run-centjes-e2e-test] client # [ 0.013788] ACPI: PM-Timer IO Port: 0x608
  298. 13.06 s [vm-test-run-centjes-e2e-test] client # [ 0.013829] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
  299. 13.06 s [vm-test-run-centjes-e2e-test] client # [ 0.013965] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
  300. 13.06 s [vm-test-run-centjes-e2e-test] client # [ 0.013971] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
  301. 13.06 s [vm-test-run-centjes-e2e-test] client # [ 0.013974] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
  302. 13.07 s [vm-test-run-centjes-e2e-test] client # [ 0.013975] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
  303. 13.07 s [vm-test-run-centjes-e2e-test] client # [ 0.013976] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
  304. 13.07 s [vm-test-run-centjes-e2e-test] client # [ 0.013977] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
  305. 13.07 s [vm-test-run-centjes-e2e-test] client # [ 0.013980] ACPI: Using ACPI (MADT) for SMP configuration information
  306. 13.07 s [vm-test-run-centjes-e2e-test] client # [ 0.013983] ACPI: HPET id: 0x8086a201 base: 0xfed00000
  307. 13.08 s [vm-test-run-centjes-e2e-test] client # [ 0.013994] TSC deadline timer available
  308. 13.08 s [vm-test-run-centjes-e2e-test] client # [ 0.014012] CPU topo: Max. logical packages: 1
  309. 13.08 s [vm-test-run-centjes-e2e-test] client # [ 0.014013] CPU topo: Max. logical dies: 1
  310. 13.08 s [vm-test-run-centjes-e2e-test] client # [ 0.014014] CPU topo: Max. dies per package: 1
  311. 13.08 s [vm-test-run-centjes-e2e-test] client # [ 0.014034] CPU topo: Max. threads per core: 1
  312. 13.08 s [vm-test-run-centjes-e2e-test] client # [ 0.014035] CPU topo: Num. cores per package: 1
  313. 13.09 s [vm-test-run-centjes-e2e-test] client # [ 0.014036] CPU topo: Num. threads per package: 1
  314. 13.09 s [vm-test-run-centjes-e2e-test] client # [ 0.014037] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs
  315. 13.09 s [vm-test-run-centjes-e2e-test] client # [ 0.014082] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()
  316. 13.09 s [vm-test-run-centjes-e2e-test] client # [ 0.014171] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]
  317. 13.09 s [vm-test-run-centjes-e2e-test] client # [ 0.014172] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]
  318. 13.10 s [vm-test-run-centjes-e2e-test] client # [ 0.014175] [mem 0x40000000-0xfeffbfff] available for PCI devices
  319. 13.10 s [vm-test-run-centjes-e2e-test] client # [ 0.014178] Booting paravirtualized kernel on KVM
  320. 13.10 s [vm-test-run-centjes-e2e-test] client # [ 0.014183] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
  321. 13.10 s [vm-test-run-centjes-e2e-test] client # [ 0.018044] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1
  322. 13.11 s [vm-test-run-centjes-e2e-test] client # [ 0.018718] percpu: Embedded 116 pages/cpu s294912 r65536 d114688 u2097152
  323. 13.11 s [vm-test-run-centjes-e2e-test] client # [ 0.018821] kvm-guest: PV spinlocks disabled, single CPU
  324. 13.12 s [vm-test-run-centjes-e2e-test] client # [ 0.018823] 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
  325. 13.12 s [vm-test-run-centjes-e2e-test] client # [ 0.018930] Unknown kernel command line parameters "regInfo=/nix/store/j7kn31fka8vp5bhm6sw8hv64iyzix7xw-closure-info/registration", will be passed to user space.
  326. 13.13 s [vm-test-run-centjes-e2e-test] client # [ 0.018964] random: crng init done
  327. 13.13 s [vm-test-run-centjes-e2e-test] client # [ 0.020237] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
  328. 13.13 s [vm-test-run-centjes-e2e-test] client # [ 0.020739] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
  329. 13.13 s [vm-test-run-centjes-e2e-test] client # [ 0.020796] Fallback order for Node 0: 0
  330. 13.13 s [vm-test-run-centjes-e2e-test] client # [ 0.020800] Built 1 zonelists, mobility grouping on. Total pages: 262009
  331. 13.14 s [vm-test-run-centjes-e2e-test] client # [ 0.020802] Policy zone: DMA32
  332. 13.14 s [vm-test-run-centjes-e2e-test] client # [ 0.021559] mem auto-init: stack:all(zero), heap alloc:on, heap free:off
  333. 13.14 s [vm-test-run-centjes-e2e-test] client # [ 0.030429] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
  334. 13.14 s [vm-test-run-centjes-e2e-test] client # [ 0.031572] allocated 2097152 bytes of page_ext
  335. 13.14 s [vm-test-run-centjes-e2e-test] client # [ 0.031607] ftrace: allocating 46208 entries in 181 pages
  336. 13.14 s [vm-test-run-centjes-e2e-test] client # [ 0.045661] ftrace: allocated 181 pages with 5 groups
  337. 13.15 s [vm-test-run-centjes-e2e-test] client # [ 0.047056] Dynamic Preempt: voluntary
  338. 13.15 s [vm-test-run-centjes-e2e-test] client # [ 0.048291] rcu: Preemptible hierarchical RCU implementation.
  339. 13.15 s [vm-test-run-centjes-e2e-test] client # [ 0.048293] rcu: RCU event tracing is enabled.
  340. 13.15 s [vm-test-run-centjes-e2e-test] client # [ 0.048295] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.
  341. 13.15 s [vm-test-run-centjes-e2e-test] client # [ 0.048296] Trampoline variant of Tasks RCU enabled.
  342. 13.15 s [vm-test-run-centjes-e2e-test] client # [ 0.048297] Rude variant of Tasks RCU enabled.
  343. 13.16 s [vm-test-run-centjes-e2e-test] client # [ 0.048298] Tracing variant of Tasks RCU enabled.
  344. 13.16 s [vm-test-run-centjes-e2e-test] client # [ 0.048299] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
  345. 13.16 s [vm-test-run-centjes-e2e-test] client # [ 0.048300] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
  346. 13.16 s [vm-test-run-centjes-e2e-test] client # [ 0.048343] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
  347. 13.17 s [vm-test-run-centjes-e2e-test] client # [ 0.048345] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
  348. 13.17 s [vm-test-run-centjes-e2e-test] client # [ 0.048346] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
  349. 13.17 s [vm-test-run-centjes-e2e-test] client # [ 0.053174] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16
  350. 13.18 s [vm-test-run-centjes-e2e-test] client # [ 0.053518] rcu: srcu_init: Setting srcu_struct sizes based on contention.
  351. 13.18 s [vm-test-run-centjes-e2e-test] client # [ 0.053670] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)
  352. 13.18 s [vm-test-run-centjes-e2e-test] client # [ 0.067139] Console: colour VGA+ 80x25
  353. 13.18 s [vm-test-run-centjes-e2e-test] client # [ 0.067146] printk: legacy console [tty0] enabled
  354. 13.18 s [vm-test-run-centjes-e2e-test] client # [ 0.175822] printk: legacy console [ttyS0] enabled
  355. 13.19 s [vm-test-run-centjes-e2e-test] client # [ 0.439092] ACPI: Core revision 20240827
  356. 13.19 s [vm-test-run-centjes-e2e-test] client # [ 0.441670] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
  357. 13.19 s [vm-test-run-centjes-e2e-test] client # [ 0.446464] APIC: Switch to symmetric I/O mode setup
  358. 13.20 s [vm-test-run-centjes-e2e-test] client # [ 0.449972] x2apic enabled
  359. 13.20 s [vm-test-run-centjes-e2e-test] client # [ 0.452518] APIC: Switched APIC routing to: physical x2apic
  360. 13.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.850923] APIC: Switch to symmetric I/O mode setup
  361. 13.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.854107] x2apic enabled
  362. 13.29 s [vm-test-run-centjes-e2e-test] client # [ 0.543811] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
  363. 13.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.856368] APIC: Switched APIC routing to: physical x2apic
  364. 13.30 s [vm-test-run-centjes-e2e-test] client # [ 0.547148] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x31024cfe468, max_idle_ns: 440795307017 ns
  365. 13.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.862738] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
  366. 13.30 s [vm-test-run-centjes-e2e-test] client # [ 0.552129] Calibrating delay loop (skipped) preset value.. 6799.99 BogoMIPS (lpj=3399998)
  367. 13.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.865418] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x31024cfe468, max_idle_ns: 440795307017 ns
  368. 13.30 s [vm-test-run-centjes-e2e-test] client # [ 0.555639] x86/cpu: User Mode Instruction Prevention (UMIP) activated
  369. 13.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.869718] Calibrating delay loop (skipped) preset value.. 6799.99 BogoMIPS (lpj=3399998)
  370. 13.31 s [vm-test-run-centjes-e2e-test] client # [ 0.558417] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127
  371. 13.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] x86/cpu: User Mode Instruction Prevention (UMIP) activated
  372. 13.31 s [vm-test-run-centjes-e2e-test] client # [ 0.560124] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0
  373. 13.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127
  374. 13.32 s [vm-test-run-centjes-e2e-test] client # [ 0.561141] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
  375. 13.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0
  376. 13.32 s [vm-test-run-centjes-e2e-test] client # [ 0.563127] Spectre V2 : Mitigation: Retpolines
  377. 13.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
  378. 13.32 s [vm-test-run-centjes-e2e-test] client # [ 0.565124] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT
  379. 13.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] Spectre V2 : Mitigation: Retpolines
  380. 13.32 s [vm-test-run-centjes-e2e-test] client # [ 0.568125] Spectre V2 : Enabling Restricted Speculation for firmware calls
  381. 13.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT
  382. 13.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] Spectre V2 : Enabling Restricted Speculation for firmware calls
  383. 13.33 s [vm-test-run-centjes-e2e-test] client # [ 0.570132] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
  384. 13.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
  385. 13.33 s [vm-test-run-centjes-e2e-test] client # [ 0.572127] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl
  386. 13.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl
  387. 13.34 s [vm-test-run-centjes-e2e-test] client # [ 0.574125] active return thunk: srso_alias_return_thunk
  388. 13.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] active return thunk: srso_alias_return_thunk
  389. 13.34 s [vm-test-run-centjes-e2e-test] client # [ 0.576135] Speculative Return Stack Overflow: Mitigation: Safe RET
  390. 13.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] Speculative Return Stack Overflow: Mitigation: Safe RET
  391. 13.34 s [vm-test-run-centjes-e2e-test] client # [ 0.578129] Transient Scheduler Attacks: Forcing mitigation on in a VM
  392. 13.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] Transient Scheduler Attacks: Forcing mitigation on in a VM
  393. 13.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] Transient Scheduler Attacks: Vulnerable: Clear CPU buffers attempted, no microcode
  394. 13.35 s [vm-test-run-centjes-e2e-test] client # [ 0.580127] Transient Scheduler Attacks: Vulnerable: Clear CPU buffers attempted, no microcode
  395. 13.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
  396. 13.35 s [vm-test-run-centjes-e2e-test] client # [ 0.582223] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
  397. 13.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
  398. 13.36 s [vm-test-run-centjes-e2e-test] client # [ 0.584126] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
  399. 13.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
  400. 13.37 s [vm-test-run-centjes-e2e-test] client # [ 0.586136] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
  401. 13.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'
  402. 13.37 s [vm-test-run-centjes-e2e-test] client # [ 0.588126] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'
  403. 13.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256
  404. 13.37 s [vm-test-run-centjes-e2e-test] client # [ 0.590128] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256
  405. 13.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] x86/fpu: xstate_offset[9]: 832, xstate_sizes[9]: 8
  406. 13.38 s [vm-test-run-centjes-e2e-test] client # [ 0.592126] x86/fpu: xstate_offset[9]: 832, xstate_sizes[9]: 8
  407. 13.38 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] x86/fpu: Enabled xstate features 0x207, context size is 840 bytes, using 'compacted' format.
  408. 13.38 s [vm-test-run-centjes-e2e-test] client # [ 0.593126] x86/fpu: Enabled xstate features 0x207, context size is 840 bytes, using 'compacted' format.
  409. 13.41 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] Freeing SMP alternatives memory: 40K
  410. 13.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] pid_max: default: 32768 minimum: 301
  411. 13.42 s [vm-test-run-centjes-e2e-test] client # [ 0.630227] Freeing SMP alternatives memory: 40K
  412. 13.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] LSM: initializing lsm=capability,landlock,yama,bpf
  413. 13.42 s [vm-test-run-centjes-e2e-test] client # [ 0.631135] pid_max: default: 32768 minimum: 301
  414. 13.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] landlock: Up and running.
  415. 13.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] Yama: becoming mindful.
  416. 13.42 s [vm-test-run-centjes-e2e-test] client # [ 0.633200] LSM: initializing lsm=capability,landlock,yama,bpf
  417. 13.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] LSM support for eBPF active
  418. 13.43 s [vm-test-run-centjes-e2e-test] client # [ 0.635267] landlock: Up and running.
  419. 13.43 s [vm-test-run-centjes-e2e-test] client # [ 0.637125] Yama: becoming mindful.
  420. 13.43 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
  421. 13.43 s [vm-test-run-centjes-e2e-test] client # [ 0.639491] LSM support for eBPF active
  422. 13.43 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
  423. 13.43 s [vm-test-run-centjes-e2e-test] client # [ 0.641207] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
  424. 13.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870709] smpboot: CPU0: AMD Ryzen 9 5950X 16-Core Processor (family: 0x19, model: 0x21, stepping: 0x0)
  425. 13.44 s [vm-test-run-centjes-e2e-test] client # [ 0.643133] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
  426. 13.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.872164] Performance Events: Fam17h+ core perfctr, AMD PMU driver.
  427. 13.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.873729] ... version: 0
  428. 13.44 s [vm-test-run-centjes-e2e-test] client # [ 0.646754] smpboot: CPU0: AMD Ryzen 9 5950X 16-Core Processor (family: 0x19, model: 0x21, stepping: 0x0)
  429. 13.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.874719] ... bit width: 48
  430. 13.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.875720] ... generic registers: 6
  431. 13.45 s [vm-test-run-centjes-e2e-test] client # [ 0.648529] Performance Events: Fam17h+ core perfctr, AMD PMU driver.
  432. 13.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.876722] ... value mask: 0000ffffffffffff
  433. 13.45 s [vm-test-run-centjes-e2e-test] client # [ 0.650140] ... version: 0
  434. 13.45 s [vm-test-run-centjes-e2e-test] client # [ 0.651131] ... bit width: 48
  435. 13.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.877719] ... max period: 00007fffffffffff
  436. 13.45 s [vm-test-run-centjes-e2e-test] client # [ 0.652131] ... generic registers: 6
  437. 13.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.878719] ... fixed-purpose events: 0
  438. 13.45 s [vm-test-run-centjes-e2e-test] client # [ 0.653131] ... value mask: 0000ffffffffffff
  439. 13.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.879722] ... event mask: 000000000000003f
  440. 13.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.880870] signal: max sigframe size: 3376
  441. 13.46 s [vm-test-run-centjes-e2e-test] client # [ 0.654132] ... max period: 00007fffffffffff
  442. 13.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.881793] rcu: Hierarchical SRCU implementation.
  443. 13.46 s [vm-test-run-centjes-e2e-test] client # [ 0.655133] ... fixed-purpose events: 0
  444. 13.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.882720] rcu: Max phase no-delay instances is 400.
  445. 13.46 s [vm-test-run-centjes-e2e-test] client # [ 0.656132] ... event mask: 000000000000003f
  446. 13.46 s [vm-test-run-centjes-e2e-test] client # [ 0.657290] signal: max sigframe size: 3376
  447. 13.47 s [vm-test-run-centjes-e2e-test] client # [ 0.658344] rcu: Hierarchical SRCU implementation.
  448. 13.47 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.887660] smp: Bringing up secondary CPUs ...
  449. 13.47 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.888746] smp: Brought up 1 node, 1 CPU
  450. 13.47 s [vm-test-run-centjes-e2e-test] client # [ 0.659133] rcu: Max phase no-delay instances is 400.
  451. 13.47 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.889727] smpboot: Total of 1 processors activated (6799.99 BogoMIPS)
  452. 13.47 s [vm-test-run-centjes-e2e-test] client # [ 0.664226] smp: Bringing up secondary CPUs ...
  453. 13.48 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.891564] Memory: 962120K/1048036K available (16384K kernel code, 2645K rwdata, 12580K rodata, 3408K init, 3360K bss, 78840K reserved, 0K cma-reserved)
  454. 13.48 s [vm-test-run-centjes-e2e-test] client # [ 0.665150] smp: Brought up 1 node, 1 CPU
  455. 13.48 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.892975] devtmpfs: initialized
  456. 13.48 s [vm-test-run-centjes-e2e-test] client # [ 0.666136] smpboot: Total of 1 processors activated (6799.99 BogoMIPS)
  457. 13.48 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.893864] x86/mm: Memory block size: 128MB
  458. 13.49 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.895378] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
  459. 13.49 s [vm-test-run-centjes-e2e-test] client # [ 0.667530] Memory: 962120K/1048036K available (16384K kernel code, 2645K rwdata, 12580K rodata, 3408K init, 3360K bss, 78836K reserved, 0K cma-reserved)
  460. 13.49 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.896725] futex hash table entries: 256 (order: 2, 16384 bytes, linear)
  461. 13.49 s [vm-test-run-centjes-e2e-test] client # [ 0.669391] devtmpfs: initialized
  462. 13.49 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.897886] pinctrl core: initialized pinctrl subsystem
  463. 13.49 s [vm-test-run-centjes-e2e-test] client # [ 0.670307] x86/mm: Memory block size: 128MB
  464. 13.49 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.899115] PM: RTC time: 16:17:42, date: 2026-08-31
  465. 13.50 s [vm-test-run-centjes-e2e-test] client # [ 0.671916] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
  466. 13.50 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.901968] NET: Registered PF_NETLINK/PF_ROUTE protocol family
  467. 13.50 s [vm-test-run-centjes-e2e-test] client # [ 0.673138] futex hash table entries: 256 (order: 2, 16384 bytes, linear)
  468. 13.50 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.903951] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations
  469. 13.50 s [vm-test-run-centjes-e2e-test] client # [ 0.674305] pinctrl core: initialized pinctrl subsystem
  470. 13.50 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.905767] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
  471. 13.50 s [vm-test-run-centjes-e2e-test] client # [ 0.675576] PM: RTC time: 16:17:42, date: 2026-08-31
  472. 13.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.907744] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
  473. 13.51 s [vm-test-run-centjes-e2e-test] client # [ 0.678254] NET: Registered PF_NETLINK/PF_ROUTE protocol family
  474. 13.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.909752] audit: initializing netlink subsys (disabled)
  475. 13.51 s [vm-test-run-centjes-e2e-test] client # [ 0.679377] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations
  476. 13.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.910820] audit: type=2000 audit(1788193062.450:1): state=initialized audit_enabled=0 res=1
  477. 13.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.912939] thermal_sys: Registered thermal governor 'bang_bang'
  478. 13.52 s [vm-test-run-centjes-e2e-test] client # [ 0.681161] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
  479. 13.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.912942] thermal_sys: Registered thermal governor 'step_wise'
  480. 13.52 s [vm-test-run-centjes-e2e-test] client # [ 0.683176] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
  481. 13.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.914724] thermal_sys: Registered thermal governor 'user_space'
  482. 13.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.915755] cpuidle: using governor menu
  483. 13.52 s [vm-test-run-centjes-e2e-test] client # [ 0.685184] audit: initializing netlink subsys (disabled)
  484. 13.53 s [vm-test-run-centjes-e2e-test] client # [ 0.686277] audit: type=2000 audit(1788193062.851:1): state=initialized audit_enabled=0 res=1
  485. 13.53 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.919323] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
  486. 13.53 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.921046] PCI: Using configuration type 1 for base access
  487. 13.53 s [vm-test-run-centjes-e2e-test] client # [ 0.688361] thermal_sys: Registered thermal governor 'bang_bang'
  488. 13.53 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.922725] PCI: Using configuration type 1 for extended access
  489. 13.53 s [vm-test-run-centjes-e2e-test] client # [ 0.688364] thermal_sys: Registered thermal governor 'step_wise'
  490. 13.54 s [vm-test-run-centjes-e2e-test] client # [ 0.690141] thermal_sys: Registered thermal governor 'user_space'
  491. 13.54 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.923985] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
  492. 13.54 s [vm-test-run-centjes-e2e-test] client # [ 0.691156] cpuidle: using governor menu
  493. 13.54 s [vm-test-run-centjes-e2e-test] client # [ 0.695063] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
  494. 13.55 s [vm-test-run-centjes-e2e-test] client # [ 0.696561] PCI: Using configuration type 1 for base access
  495. 13.55 s [vm-test-run-centjes-e2e-test] client # [ 0.698130] PCI: Using configuration type 1 for extended access
  496. 13.55 s [vm-test-run-centjes-e2e-test] client # [ 0.699436] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
  497. 13.56 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.948930] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
  498. 13.57 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.950719] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page
  499. 13.57 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.951720] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
  500. 13.57 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.952721] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
  501. 13.58 s [vm-test-run-centjes-e2e-test] client # [ 0.722313] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
  502. 13.58 s [vm-test-run-centjes-e2e-test] client # [ 0.724133] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page
  503. 13.58 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.960735] ACPI: Added _OSI(Module Device)
  504. 13.58 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.961720] ACPI: Added _OSI(Processor Device)
  505. 13.58 s [vm-test-run-centjes-e2e-test] client # [ 0.725135] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
  506. 13.58 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.962724] ACPI: Added _OSI(Processor Aggregator Device)
  507. 13.59 s [vm-test-run-centjes-e2e-test] client # [ 0.726134] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
  508. 13.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.965612] ACPI: 1 ACPI AML tables successfully acquired and loaded
  509. 13.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.967994] ACPI: Interpreter enabled
  510. 13.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.968746] ACPI: PM: (supports S0 S3 S4 S5)
  511. 13.60 s [vm-test-run-centjes-e2e-test] client # [ 0.733389] ACPI: Added _OSI(Module Device)
  512. 13.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.969722] ACPI: Using IOAPIC for interrupt routing
  513. 13.60 s [vm-test-run-centjes-e2e-test] client # [ 0.735137] ACPI: Added _OSI(Processor Device)
  514. 13.60 s [vm-test-run-centjes-e2e-test] client # [ 0.736154] ACPI: Added _OSI(Processor Aggregator Device)
  515. 13.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.971256] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
  516. 13.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.972722] PCI: Using E820 reservations for host bridge windows
  517. 13.61 s [vm-test-run-centjes-e2e-test] client # [ 0.739034] ACPI: 1 ACPI AML tables successfully acquired and loaded
  518. 13.61 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.973941] ACPI: Enabled 2 GPEs in block 00 to 0F
  519. 13.61 s [vm-test-run-centjes-e2e-test] client # [ 0.741545] ACPI: Interpreter enabled
  520. 13.61 s [vm-test-run-centjes-e2e-test] client # [ 0.743164] ACPI: PM: (supports S0 S3 S4 S5)
  521. 13.61 s [vm-test-run-centjes-e2e-test] client # [ 0.744133] ACPI: Using IOAPIC for interrupt routing
  522. 13.61 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.979174] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
  523. 13.62 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.980729] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]
  524. 13.62 s [vm-test-run-centjes-e2e-test] client # [ 0.745210] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
  525. 13.62 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.983091] acpiphp: Slot [3] registered
  526. 13.62 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.984767] acpiphp: Slot [4] registered
  527. 13.62 s [vm-test-run-centjes-e2e-test] client # [ 0.747138] PCI: Using E820 reservations for host bridge windows
  528. 13.62 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.985776] acpiphp: Slot [5] registered
  529. 13.63 s [vm-test-run-centjes-e2e-test] client # [ 0.748447] ACPI: Enabled 2 GPEs in block 00 to 0F
  530. 13.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.986771] acpiphp: Slot [6] registered
  531. 13.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.987759] acpiphp: Slot [7] registered
  532. 13.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.988771] acpiphp: Slot [8] registered
  533. 13.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.989770] acpiphp: Slot [9] registered
  534. 13.63 s [vm-test-run-centjes-e2e-test] client # [ 0.754597] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
  535. 13.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.990766] acpiphp: Slot [10] registered
  536. 13.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.991797] acpiphp: Slot [11] registered
  537. 13.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.992766] acpiphp: Slot [12] registered
  538. 13.64 s [vm-test-run-centjes-e2e-test] client # [ 0.756146] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]
  539. 13.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.993773] acpiphp: Slot [13] registered
  540. 13.64 s [vm-test-run-centjes-e2e-test] client # [ 0.758681] acpiphp: Slot [3] registered
  541. 13.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.994767] acpiphp: Slot [14] registered
  542. 13.64 s [vm-test-run-centjes-e2e-test] client # [ 0.760192] acpiphp: Slot [4] registered
  543. 13.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.995769] acpiphp: Slot [15] registered
  544. 13.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.996776] acpiphp: Slot [16] registered
  545. 13.65 s [vm-test-run-centjes-e2e-test] client # [ 0.761218] acpiphp: Slot [5] registered
  546. 13.65 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.997769] acpiphp: Slot [17] registered
  547. 13.65 s [vm-test-run-centjes-e2e-test] client # [ 0.762192] acpiphp: Slot [6] registered
  548. 13.65 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.998772] acpiphp: Slot [18] registered
  549. 13.65 s [vm-test-run-centjes-e2e-test] client # [ 0.763193] acpiphp: Slot [7] registered
  550. 13.65 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.999771] acpiphp: Slot [19] registered
  551. 13.65 s [vm-test-run-centjes-e2e-test] client # [ 0.764190] acpiphp: Slot [8] registered
  552. 13.65 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.000781] acpiphp: Slot [20] registered
  553. 13.65 s [vm-test-run-centjes-e2e-test] client # [ 0.765199] acpiphp: Slot [9] registered
  554. 13.65 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.001777] acpiphp: Slot [21] registered
  555. 13.66 s [vm-test-run-centjes-e2e-test] client # [ 0.766190] acpiphp: Slot [10] registered
  556. 13.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.002771] acpiphp: Slot [22] registered
  557. 13.66 s [vm-test-run-centjes-e2e-test] client # [ 0.767186] acpiphp: Slot [11] registered
  558. 13.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.003769] acpiphp: Slot [23] registered
  559. 13.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.004774] acpiphp: Slot [24] registered
  560. 13.66 s [vm-test-run-centjes-e2e-test] client # [ 0.768214] acpiphp: Slot [12] registered
  561. 13.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.005769] acpiphp: Slot [25] registered
  562. 13.66 s [vm-test-run-centjes-e2e-test] client # [ 0.769236] acpiphp: Slot [13] registered
  563. 13.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.006771] acpiphp: Slot [26] registered
  564. 13.67 s [vm-test-run-centjes-e2e-test] client # [ 0.770207] acpiphp: Slot [14] registered
  565. 13.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.007760] acpiphp: Slot [27] registered
  566. 13.67 s [vm-test-run-centjes-e2e-test] client # [ 0.771207] acpiphp: Slot [15] registered
  567. 13.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.008764] acpiphp: Slot [28] registered
  568. 13.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.009763] acpiphp: Slot [29] registered
  569. 13.67 s [vm-test-run-centjes-e2e-test] client # [ 0.772218] acpiphp: Slot [16] registered
  570. 13.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.010763] acpiphp: Slot [30] registered
  571. 13.67 s [vm-test-run-centjes-e2e-test] client # [ 0.773194] acpiphp: Slot [17] registered
  572. 13.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.011776] acpiphp: Slot [31] registered
  573. 13.68 s [vm-test-run-centjes-e2e-test] client # [ 0.774203] acpiphp: Slot [18] registered
  574. 13.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.012768] PCI host bridge to bus 0000:00
  575. 13.68 s [vm-test-run-centjes-e2e-test] client # [ 0.775178] acpiphp: Slot [19] registered
  576. 13.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.013733] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
  577. 13.68 s [vm-test-run-centjes-e2e-test] client # [ 0.776197] acpiphp: Slot [20] registered
  578. 13.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.014722] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
  579. 13.68 s [vm-test-run-centjes-e2e-test] client # [ 0.777197] acpiphp: Slot [21] registered
  580. 13.68 s [vm-test-run-centjes-e2e-test] client # [ 0.778194] acpiphp: Slot [22] registered
  581. 13.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.015721] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
  582. 13.69 s [vm-test-run-centjes-e2e-test] client # [ 0.779180] acpiphp: Slot [23] registered
  583. 13.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.017721] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
  584. 13.69 s [vm-test-run-centjes-e2e-test] client # [ 0.780262] acpiphp: Slot [24] registered
  585. 13.69 s [vm-test-run-centjes-e2e-test] client # [ 0.781201] acpiphp: Slot [25] registered
  586. 13.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.019721] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
  587. 13.69 s [vm-test-run-centjes-e2e-test] client # [ 0.782202] acpiphp: Slot [26] registered
  588. 13.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.021722] pci_bus 0000:00: root bus resource [bus 00-ff]
  589. 13.69 s [vm-test-run-centjes-e2e-test] client # [ 0.783195] acpiphp: Slot [27] registered
  590. 13.70 s [vm-test-run-centjes-e2e-test] client # [ 0.784207] acpiphp: Slot [28] registered
  591. 13.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.023200] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint
  592. 13.70 s [vm-test-run-centjes-e2e-test] client # [ 0.785201] acpiphp: Slot [29] registered
  593. 13.70 s [vm-test-run-centjes-e2e-test] client # [ 0.786200] acpiphp: Slot [30] registered
  594. 13.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.025840] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint
  595. 13.70 s [vm-test-run-centjes-e2e-test] client # [ 0.787202] acpiphp: Slot [31] registered
  596. 13.71 s [vm-test-run-centjes-e2e-test] client # [ 0.788196] PCI host bridge to bus 0000:00
  597. 13.71 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.029313] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint
  598. 13.71 s [vm-test-run-centjes-e2e-test] client # [ 0.789168] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
  599. 13.71 s [vm-test-run-centjes-e2e-test] client # [ 0.791140] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
  600. 13.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.035721] pci 0000:00:01.1: BAR 4 [io 0xc1e0-0xc1ef]
  601. 13.72 s [vm-test-run-centjes-e2e-test] client # [ 0.793139] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
  602. 13.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.038786] pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk
  603. 13.72 s [vm-test-run-centjes-e2e-test] client # [ 0.795138] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
  604. 13.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.040719] pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk
  605. 13.73 s [vm-test-run-centjes-e2e-test] client # [ 0.797136] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
  606. 13.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.041719] pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk
  607. 13.73 s [vm-test-run-centjes-e2e-test] client # [ 0.799136] pci_bus 0000:00: root bus resource [bus 00-ff]
  608. 13.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.042718] pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk
  609. 13.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.044200] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint
  610. 13.73 s [vm-test-run-centjes-e2e-test] client # [ 0.800736] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint
  611. 13.74 s [vm-test-run-centjes-e2e-test] client # [ 0.803455] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint
  612. 13.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.049111] pci 0000:00:01.2: BAR 4 [io 0xc100-0xc11f]
  613. 13.75 s [vm-test-run-centjes-e2e-test] client # [ 0.807199] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint
  614. 13.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.053577] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint
  615. 13.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.055817] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI
  616. 13.75 s [vm-test-run-centjes-e2e-test] client # [ 0.814115] pci 0000:00:01.1: BAR 4 [io 0xc1e0-0xc1ef]
  617. 13.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.057740] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB
  618. 13.76 s [vm-test-run-centjes-e2e-test] client # [ 0.817200] pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk
  619. 13.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.059271] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint
  620. 13.76 s [vm-test-run-centjes-e2e-test] client # [ 0.819132] pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk
  621. 13.77 s [vm-test-run-centjes-e2e-test] client # [ 0.820133] pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk
  622. 13.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.062705] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]
  623. 13.77 s [vm-test-run-centjes-e2e-test] client # [ 0.821132] pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk
  624. 13.77 s [vm-test-run-centjes-e2e-test] client # [ 0.822742] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint
  625. 13.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.066709] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]
  626. 13.78 s [vm-test-run-centjes-e2e-test] client # [ 0.829742] pci 0000:00:01.2: BAR 4 [io 0xc100-0xc11f]
  627. 13.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.073866] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref]
  628. 13.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.076029] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
  629. 13.79 s [vm-test-run-centjes-e2e-test] client # [ 0.834039] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint
  630. 13.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.078523] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
  631. 13.80 s [vm-test-run-centjes-e2e-test] client # [ 0.836736] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI
  632. 13.80 s [vm-test-run-centjes-e2e-test] client # [ 0.838164] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB
  633. 13.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.081572] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]
  634. 13.80 s [vm-test-run-centjes-e2e-test] client # [ 0.839864] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint
  635. 13.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.084537] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]
  636. 13.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.089720] pci 0000:00:03.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]
  637. 13.82 s [vm-test-run-centjes-e2e-test] client # [ 0.843293] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]
  638. 13.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.091720] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]
  639. 13.83 s [vm-test-run-centjes-e2e-test] client # [ 0.848108] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]
  640. 13.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.096319] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
  641. 13.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.099470] pci 0000:00:04.0: BAR 0 [io 0xc140-0xc15f]
  642. 13.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.101719] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]
  643. 13.84 s [vm-test-run-centjes-e2e-test] client # [ 0.856122] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref]
  644. 13.85 s [vm-test-run-centjes-e2e-test] client # [ 0.858742] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
  645. 13.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.106719] pci 0000:00:04.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]
  646. 13.85 s [vm-test-run-centjes-e2e-test] client # [ 0.861119] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
  647. 13.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.111547] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
  648. 13.86 s [vm-test-run-centjes-e2e-test] client # [ 0.865050] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]
  649. 13.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.113719] pci 0000:00:05.0: BAR 0 [io 0xc080-0xc0bf]
  650. 13.86 s [vm-test-run-centjes-e2e-test] client # [ 0.867132] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]
  651. 13.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.115722] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]
  652. 13.87 s [vm-test-run-centjes-e2e-test] client # [ 0.875076] pci 0000:00:03.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]
  653. 13.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.119719] pci 0000:00:05.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]
  654. 13.88 s [vm-test-run-centjes-e2e-test] client # [ 0.877133] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]
  655. 13.89 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.126637] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
  656. 13.89 s [vm-test-run-centjes-e2e-test] client # [ 0.883263] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
  657. 13.89 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.129361] pci 0000:00:06.0: BAR 0 [io 0xc160-0xc17f]
  658. 13.89 s [vm-test-run-centjes-e2e-test] client # [ 0.886896] pci 0000:00:04.0: BAR 0 [io 0xc140-0xc15f]
  659. 13.89 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.131720] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]
  660. 13.90 s [vm-test-run-centjes-e2e-test] client # [ 0.889134] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]
  661. 13.90 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.136358] pci 0000:00:06.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]
  662. 13.91 s [vm-test-run-centjes-e2e-test] client # [ 0.895135] pci 0000:00:04.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]
  663. 13.91 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.141666] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
  664. 13.92 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.143719] pci 0000:00:07.0: BAR 0 [io 0xc180-0xc19f]
  665. 13.92 s [vm-test-run-centjes-e2e-test] client # [ 0.902769] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
  666. 13.92 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.145720] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]
  667. 13.93 s [vm-test-run-centjes-e2e-test] client # [ 0.905131] pci 0000:00:05.0: BAR 0 [io 0xc080-0xc0bf]
  668. 13.93 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.149745] pci 0000:00:07.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]
  669. 13.93 s [vm-test-run-centjes-e2e-test] client # [ 0.907131] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]
  670. 13.94 s [vm-test-run-centjes-e2e-test] client # [ 0.912135] pci 0000:00:05.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]
  671. 13.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.155807] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint
  672. 13.95 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.159532] pci 0000:00:08.0: BAR 0 [io 0xc000-0xc07f]
  673. 13.95 s [vm-test-run-centjes-e2e-test] client # [ 0.918203] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
  674. 13.95 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.161721] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]
  675. 13.95 s [vm-test-run-centjes-e2e-test] client # [ 0.921131] pci 0000:00:06.0: BAR 0 [io 0xc160-0xc17f]
  676. 13.96 s [vm-test-run-centjes-e2e-test] client # [ 0.923135] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]
  677. 13.96 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.166719] pci 0000:00:08.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]
  678. 13.97 s [vm-test-run-centjes-e2e-test] client # [ 0.927140] pci 0000:00:06.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]
  679. 13.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.172764] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
  680. 13.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.175719] pci 0000:00:09.0: BAR 0 [io 0xc1a0-0xc1bf]
  681. 13.98 s [vm-test-run-centjes-e2e-test] client # [ 0.933922] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
  682. 13.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.177721] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]
  683. 13.98 s [vm-test-run-centjes-e2e-test] client # [ 0.936132] pci 0000:00:07.0: BAR 0 [io 0xc180-0xc19f]
  684. 13.99 s [vm-test-run-centjes-e2e-test] client # [ 0.938132] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]
  685. 13.99 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.181720] pci 0000:00:09.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]
  686. 14.00 s [vm-test-run-centjes-e2e-test] client # [ 0.942132] pci 0000:00:07.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]
  687. 14.01 s [vm-test-run-centjes-e2e-test] client # [ 0.947470] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint
  688. 14.01 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.183719] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref]
  689. 14.01 s [vm-test-run-centjes-e2e-test] client # [ 0.950658] pci 0000:00:08.0: BAR 0 [io 0xc000-0xc07f]
  690. 14.01 s [vm-test-run-centjes-e2e-test] client # [ 0.953133] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]
  691. 14.01 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.188683] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint
  692. 14.02 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.191700] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]
  693. 14.03 s [vm-test-run-centjes-e2e-test] client # [ 0.959089] pci 0000:00:08.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]
  694. 14.03 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.195722] pci 0000:00:0a.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]
  695. 14.04 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.200785] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint
  696. 14.04 s [vm-test-run-centjes-e2e-test] client # [ 0.965084] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
  697. 14.04 s [vm-test-run-centjes-e2e-test] client # [ 0.967980] pci 0000:00:09.0: BAR 0 [io 0xc1a0-0xc1bf]
  698. 14.04 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.205721] pci 0000:00:0b.0: BAR 0 [io 0xc0c0-0xc0ff]
  699. 14.05 s [vm-test-run-centjes-e2e-test] client # [ 0.970136] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]
  700. 14.05 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.207720] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]
  701. 14.06 s [vm-test-run-centjes-e2e-test] client # [ 0.975151] pci 0000:00:09.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]
  702. 14.06 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.212607] pci 0000:00:0b.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]
  703. 14.07 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.217655] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
  704. 14.07 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.219727] pci 0000:00:0c.0: BAR 0 [io 0xc1c0-0xc1df]
  705. 14.07 s [vm-test-run-centjes-e2e-test] client # [ 0.977132] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref]
  706. 14.08 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.221722] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff]
  707. 14.08 s [vm-test-run-centjes-e2e-test] client # [ 0.981663] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint
  708. 14.08 s [vm-test-run-centjes-e2e-test] client # [ 0.985005] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]
  709. 14.08 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.225719] pci 0000:00:0c.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]
  710. 14.09 s [vm-test-run-centjes-e2e-test] client # [ 0.989683] pci 0000:00:0a.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]
  711. 14.10 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.234720] ACPI: PCI: Interrupt link LNKA configured for IRQ 10
  712. 14.10 s [vm-test-run-centjes-e2e-test] client # [ 0.994697] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint
  713. 14.10 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.236999] ACPI: PCI: Interrupt link LNKB configured for IRQ 10
  714. 14.10 s [vm-test-run-centjes-e2e-test] client # [ 0.997133] pci 0000:00:0b.0: BAR 0 [io 0xc0c0-0xc0ff]
  715. 14.10 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.238958] ACPI: PCI: Interrupt link LNKC configured for IRQ 11
  716. 14.10 s [vm-test-run-centjes-e2e-test] client # [ 0.999133] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]
  717. 14.11 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.240941] ACPI: PCI: Interrupt link LNKD configured for IRQ 11
  718. 14.11 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.242855] ACPI: PCI: Interrupt link LNKS configured for IRQ 9
  719. 14.11 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.244292] iommu: Default domain type: Translated
  720. 14.12 s [vm-test-run-centjes-e2e-test] client # [ 1.004780] pci 0000:00:0b.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]
  721. 14.12 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.245718] iommu: DMA domain TLB invalidation policy: lazy mode
  722. 14.12 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.246790] ACPI: bus type USB registered
  723. 14.12 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.247766] usbcore: registered new interface driver usbfs
  724. 14.12 s [vm-test-run-centjes-e2e-test] client # [ 1.009618] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
  725. 14.12 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.248740] usbcore: registered new interface driver hub
  726. 14.12 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.249744] usbcore: registered new device driver usb
  727. 14.12 s [vm-test-run-centjes-e2e-test] client # [ 1.012131] pci 0000:00:0c.0: BAR 0 [io 0xc1c0-0xc1df]
  728. 14.13 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.251264] NetLabel: Initializing
  729. 14.13 s [vm-test-run-centjes-e2e-test] client # [ 1.014131] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff]
  730. 14.13 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.252718] NetLabel: domain hash size = 128
  731. 14.13 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.253718] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO
  732. 14.13 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.254752] NetLabel: unlabeled traffic allowed by default
  733. 14.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.255720] PCI: Using ACPI for IRQ routing
  734. 14.14 s [vm-test-run-centjes-e2e-test] client # [ 1.019132] pci 0000:00:0c.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]
  735. 14.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.257709] pci 0000:00:02.0: vgaarb: setting as boot VGA device
  736. 14.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.257709] pci 0000:00:02.0: vgaarb: bridge control possible
  737. 14.15 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.257709] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
  738. 14.15 s [vm-test-run-centjes-e2e-test] client # [ 1.026810] ACPI: PCI: Interrupt link LNKA configured for IRQ 10
  739. 14.15 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.257735] vgaarb: loaded
  740. 14.15 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.258882] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
  741. 14.15 s [vm-test-run-centjes-e2e-test] client # [ 1.028382] ACPI: PCI: Interrupt link LNKB configured for IRQ 10
  742. 14.15 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.259719] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
  743. 14.16 s [vm-test-run-centjes-e2e-test] client # [ 1.030408] ACPI: PCI: Interrupt link LNKC configured for IRQ 11
  744. 14.16 s [vm-test-run-centjes-e2e-test] client # [ 1.032385] ACPI: PCI: Interrupt link LNKD configured for IRQ 11
  745. 14.16 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.264850] clocksource: Switched to clocksource kvm-clock
  746. 14.16 s [vm-test-run-centjes-e2e-test] client # [ 1.034271] ACPI: PCI: Interrupt link LNKS configured for IRQ 9
  747. 14.16 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.268712] VFS: Disk quotas dquot_6.6.0
  748. 14.17 s [vm-test-run-centjes-e2e-test] client # [ 1.036897] iommu: Default domain type: Translated
  749. 14.17 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.270701] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
  750. 14.17 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.273973] pnp: PnP ACPI init
  751. 14.17 s [vm-test-run-centjes-e2e-test] client # [ 1.038132] iommu: DMA domain TLB invalidation policy: lazy mode
  752. 14.17 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.276391] pnp: PnP ACPI: found 6 devices
  753. 14.17 s [vm-test-run-centjes-e2e-test] client # [ 1.040329] ACPI: bus type USB registered
  754. 14.18 s [vm-test-run-centjes-e2e-test] client # [ 1.042200] usbcore: registered new interface driver usbfs
  755. 14.18 s [vm-test-run-centjes-e2e-test] client # [ 1.044172] usbcore: registered new interface driver hub
  756. 14.18 s [vm-test-run-centjes-e2e-test] client # [ 1.045175] usbcore: registered new device driver usb
  757. 14.18 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.285491] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
  758. 14.19 s [vm-test-run-centjes-e2e-test] client # [ 1.046824] NetLabel: Initializing
  759. 14.19 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.289555] clocksource: Switched to clocksource acpi_pm
  760. 14.19 s [vm-test-run-centjes-e2e-test] client # [ 1.048132] NetLabel: domain hash size = 128
  761. 14.19 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.292342] NET: Registered PF_INET protocol family
  762. 14.19 s [vm-test-run-centjes-e2e-test] client # [ 1.049132] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO
  763. 14.19 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.294888] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
  764. 14.19 s [vm-test-run-centjes-e2e-test] client # [ 1.050162] NetLabel: unlabeled traffic allowed by default
  765. 14.20 s [vm-test-run-centjes-e2e-test] client # [ 1.051133] PCI: Using ACPI for IRQ routing
  766. 14.20 s [vm-test-run-centjes-e2e-test] client # [ 1.054185] pci 0000:00:02.0: vgaarb: setting as boot VGA device
  767. 14.20 s [vm-test-run-centjes-e2e-test] client # [ 1.055122] pci 0000:00:02.0: vgaarb: bridge control possible
  768. 14.21 s [vm-test-run-centjes-e2e-test] client # [ 1.055122] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
  769. 14.21 s [vm-test-run-centjes-e2e-test] client # [ 1.055142] vgaarb: loaded
  770. 14.21 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.313157] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
  771. 14.21 s [vm-test-run-centjes-e2e-test] client # [ 1.056271] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
  772. 14.22 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.318681] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
  773. 14.22 s [vm-test-run-centjes-e2e-test] client # [ 1.057131] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
  774. 14.22 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.322281] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
  775. 14.22 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.325935] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)
  776. 14.22 s [vm-test-run-centjes-e2e-test] client # [ 1.062272] clocksource: Switched to clocksource kvm-clock
  777. 14.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.329457] TCP: Hash tables configured (established 8192 bind 8192)
  778. 14.23 s [vm-test-run-centjes-e2e-test] client # [ 1.067032] VFS: Disk quotas dquot_6.6.0
  779. 14.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.333140] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)
  780. 14.23 s [vm-test-run-centjes-e2e-test] client # [ 1.069341] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
  781. 14.23 s [vm-test-run-centjes-e2e-test] client # [ 1.072768] pnp: PnP ACPI init
  782. 14.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.337083] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
  783. 14.24 s [vm-test-run-centjes-e2e-test] client # [ 1.075233] pnp: PnP ACPI: found 6 devices
  784. 14.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.340071] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
  785. 14.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.343354] NET: Registered PF_UNIX/PF_LOCAL protocol family
  786. 14.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.346319] NET: Registered PF_XDP protocol family
  787. 14.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.348818] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]
  788. 14.25 s [vm-test-run-centjes-e2e-test] client # [ 1.083838] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
  789. 14.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.351840] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]
  790. 14.25 s [vm-test-run-centjes-e2e-test] client # [ 1.088134] clocksource: Switched to clocksource acpi_pm
  791. 14.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.354873] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
  792. 14.25 s [vm-test-run-centjes-e2e-test] client # [ 1.091100] NET: Registered PF_INET protocol family
  793. 14.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.358055] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window]
  794. 14.26 s [vm-test-run-centjes-e2e-test] client # [ 1.094073] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
  795. 14.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.361328] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
  796. 14.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.364905] pci 0000:00:01.0: PIIX3: Enabling Passive Release
  797. 14.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.367818] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
  798. 14.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.372242] ACPI: \_SB_.LNKD: Enabled at IRQ 11
  799. 14.27 s [vm-test-run-centjes-e2e-test] client # [ 1.109274] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
  800. 14.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.376910] PCI: CLS 0 bytes, default 64
  801. 14.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.379291] Trying to unpack rootfs image as initramfs...
  802. 14.28 s [vm-test-run-centjes-e2e-test] client # [ 1.113864] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
  803. 14.28 s [vm-test-run-centjes-e2e-test] client # [ 1.118293] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
  804. 14.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.384265] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x31024cfe468, max_idle_ns: 440795307017 ns
  805. 14.28 s [vm-test-run-centjes-e2e-test] client # [ 1.122694] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)
  806. 14.29 s [vm-test-run-centjes-e2e-test] client # [ 1.126739] TCP: Hash tables configured (established 8192 bind 8192)
  807. 14.29 s [vm-test-run-centjes-e2e-test] client # [ 1.130470] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)
  808. 14.30 s [vm-test-run-centjes-e2e-test] client # [ 1.134758] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
  809. 14.30 s [vm-test-run-centjes-e2e-test] client # [ 1.137990] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
  810. 14.30 s [vm-test-run-centjes-e2e-test] client # [ 1.141574] NET: Registered PF_UNIX/PF_LOCAL protocol family
  811. 14.31 s [vm-test-run-centjes-e2e-test] client # [ 1.144664] NET: Registered PF_XDP protocol family
  812. 14.31 s [vm-test-run-centjes-e2e-test] client # [ 1.147166] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]
  813. 14.31 s [vm-test-run-centjes-e2e-test] client # [ 1.150293] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]
  814. 14.32 s [vm-test-run-centjes-e2e-test] client # [ 1.153676] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
  815. 14.32 s [vm-test-run-centjes-e2e-test] client # [ 1.157447] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window]
  816. 14.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.423384] Initialise system trusted keyrings
  817. 14.32 s [vm-test-run-centjes-e2e-test] client # [ 1.160969] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
  818. 14.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.428393] workingset: timestamp_bits=40 max_order=18 bucket_order=0
  819. 14.33 s [vm-test-run-centjes-e2e-test] client # [ 1.164458] pci 0000:00:01.0: PIIX3: Enabling Passive Release
  820. 14.33 s [vm-test-run-centjes-e2e-test] client # [ 1.167378] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
  821. 14.33 s [vm-test-run-centjes-e2e-test] client # [ 1.171967] ACPI: \_SB_.LNKD: Enabled at IRQ 11
  822. 14.34 s [vm-test-run-centjes-e2e-test] client # [ 1.176716] PCI: CLS 0 bytes, default 64
  823. 14.35 s [vm-test-run-centjes-e2e-test] client # [ 1.186517] Trying to unpack rootfs image as initramfs...
  824. 14.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.456322] Key type asymmetric registered
  825. 14.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.462410] Asymmetric key parser 'x509' registered
  826. 14.36 s [vm-test-run-centjes-e2e-test] client # [ 1.199787] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x31024cfe468, max_idle_ns: 440795307017 ns
  827. 14.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.468287] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
  828. 14.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.477219] io scheduler mq-deadline registered
  829. 14.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.479519] io scheduler kyber registered
  830. 14.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.487081] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
  831. 14.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.495546] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
  832. 14.59 s [vm-test-run-centjes-e2e-test] client # [ 1.235049] Initialise system trusted keyrings
  833. 14.59 s [vm-test-run-centjes-e2e-test] client # [ 1.240785] workingset: timestamp_bits=40 max_order=18 bucket_order=0
  834. 14.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.507193] Linux agpgart interface v0.103
  835. 14.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.513122] ACPI: bus type drm_connector registered
  836. 14.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.520781] usbcore: registered new interface driver usbserial_generic
  837. 14.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.528304] usbserial: USB Serial support registered for generic
  838. 14.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.531528] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled
  839. 14.59 s [vm-test-run-centjes-e2e-test] client # [ 1.269698] Key type asymmetric registered
  840. 14.59 s [vm-test-run-centjes-e2e-test] client # [ 1.274705] Asymmetric key parser 'x509' registered
  841. 14.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.542191] drop_monitor: Initializing network drop monitor service
  842. 14.59 s [vm-test-run-centjes-e2e-test] client # [ 1.282792] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
  843. 14.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.550368] NET: Registered PF_INET6 protocol family
  844. 14.59 s [vm-test-run-centjes-e2e-test] client # [ 1.293108] io scheduler mq-deadline registered
  845. 14.59 s [vm-test-run-centjes-e2e-test] client # [ 1.295573] io scheduler kyber registered
  846. 14.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.561766] Freeing initrd memory: 12300K
  847. 14.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.564390] Segment Routing with IPv6
  848. 14.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.566822] In-situ OAM (IOAM) with IPv6
  849. 14.59 s [vm-test-run-centjes-e2e-test] client # [ 1.303407] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
  850. 14.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.569403] IPI shorthand broadcast: enabled
  851. 14.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.574236] registered taskstats version 1
  852. 14.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.576782] Loading compiled-in X.509 certificates
  853. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.311191] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
  854. 14.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.584770] Demotion targets for Node 0: null
  855. 14.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.587294] Key type .fscrypt registered
  856. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.322858] Linux agpgart interface v0.103
  857. 14.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.589261] Key type fscrypt-provisioning registered
  858. 14.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.591946] PM: Magic number: 6:759:288
  859. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.328699] ACPI: bus type drm_connector registered
  860. 14.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.594121] tty tty21: hash matches
  861. 14.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.597227] RAS: Correctable Errors collector initialized.
  862. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.335396] Freeing initrd memory: 12300K
  863. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.338146] usbcore: registered new interface driver usbserial_generic
  864. 14.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.603515] Unstable clock detected, switching default tracing clock to "global"
  865. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.341466] usbserial: USB Serial support registered for generic
  866. 14.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.603515] If you want to keep using the local clock, then add:
  867. 14.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.603515] "trace_clock=local"
  868. 14.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.603515] on the kernel command line
  869. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.344499] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled
  870. 14.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.612754] clk: Disabling unused clocks
  871. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.348546] drop_monitor: Initializing network drop monitor service
  872. 14.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.614914] PM: genpd: Disabling unused power domains
  873. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.351869] NET: Registered PF_INET6 protocol family
  874. 14.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.618874] Freeing unused decrypted memory: 2028K
  875. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.355754] Segment Routing with IPv6
  876. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.357987] In-situ OAM (IOAM) with IPv6
  877. 14.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.622258] Freeing unused kernel image (initmem) memory: 3408K
  878. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.360421] IPI shorthand broadcast: enabled
  879. 14.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.625462] Write protecting the kernel read-only data: 30720k
  880. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.365540] registered taskstats version 1
  881. 14.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.629112] Freeing unused kernel image (rodata/data gap) memory: 1756K
  882. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.368085] Loading compiled-in X.509 certificates
  883. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.376541] Demotion targets for Node 0: null
  884. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.379080] Key type .fscrypt registered
  885. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.381254] Key type fscrypt-provisioning registered
  886. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.384023] PM: Magic number: 6:759:288
  887. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.386211] tty tty21: hash matches
  888. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.389328] RAS: Correctable Errors collector initialized.
  889. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.395496] Unstable clock detected, switching default tracing clock to "global"
  890. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.395496] If you want to keep using the local clock, then add:
  891. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.395496] "trace_clock=local"
  892. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.395496] on the kernel command line
  893. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.404898] clk: Disabling unused clocks
  894. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.407264] PM: genpd: Disabling unused power domains
  895. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.411145] Freeing unused decrypted memory: 2028K
  896. 14.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.677813] x86/mm: Checked W+X mappings: passed, no W+X pages found.
  897. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.414836] Freeing unused kernel image (initmem) memory: 3408K
  898. 14.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.681059] Run /init as init process
  899. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.417666] Write protecting the kernel read-only data: 30720k
  900. 14.60 s [vm-test-run-centjes-e2e-test] client # [ 1.420966] Freeing unused kernel image (rodata/data gap) memory: 1756K
  901. 14.75 s [vm-test-run-centjes-e2e-test] client # [ 1.467124] x86/mm: Checked W+X mappings: passed, no W+X pages found.
  902. 14.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.732294] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev
  903. 14.75 s [vm-test-run-centjes-e2e-test] client # [ 1.470263] Run /init as init process
  904. 14.75 s [vm-test-run-centjes-e2e-test] client # [ 1.520413] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev
  905. 14.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.936651] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
  906. 14.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.959903] uhci_hcd 0000:00:01.2: UHCI Host Controller
  907. 14.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.968303] SCSI subsystem initialized
  908. 14.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.972320] ACPI: \_SB_.LNKC: Enabled at IRQ 10
  909. 14.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.975275] serio: i8042 KBD port at 0x60,0x64 irq 1
  910. 14.88 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.977845] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
  911. 14.88 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.985681] serio: i8042 AUX port at 0x60,0x64 irq 12
  912. 14.89 s [vm-test-run-centjes-e2e-test] client # [ 1.720722] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
  913. 14.89 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.990382] uhci_hcd 0000:00:01.2: detected 2 ports
  914. 14.90 s [vm-test-run-centjes-e2e-test] client # [ 1.735218] uhci_hcd 0000:00:01.2: UHCI Host Controller
  915. 14.90 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.001386] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100
  916. 14.91 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.013538] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.12
  917. 14.92 s [vm-test-run-centjes-e2e-test] client # [ 1.751690] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
  918. 14.92 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.017710] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
  919. 14.92 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.021402] usb usb1: Product: UHCI Host Controller
  920. 14.92 s [vm-test-run-centjes-e2e-test] client # [ 1.757353] serio: i8042 KBD port at 0x60,0x64 irq 1
  921. 14.92 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.023933] usb usb1: Manufacturer: Linux 6.12.62 uhci_hcd
  922. 14.92 s [vm-test-run-centjes-e2e-test] client # [ 1.760887] SCSI subsystem initialized
  923. 14.93 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.026691] usb usb1: SerialNumber: 0000:00:01.2
  924. 14.93 s [vm-test-run-centjes-e2e-test] client # [ 1.765679] serio: i8042 AUX port at 0x60,0x64 irq 12
  925. 14.93 s [vm-test-run-centjes-e2e-test] client # [ 1.769542] ACPI: \_SB_.LNKC: Enabled at IRQ 10
  926. 14.93 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.034358] ACPI: \_SB_.LNKA: Enabled at IRQ 10
  927. 14.94 s [vm-test-run-centjes-e2e-test] client # [ 1.776464] uhci_hcd 0000:00:01.2: detected 2 ports
  928. 14.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.045176] scsi host0: ata_piix
  929. 14.95 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.051376] scsi host1: ata_piix
  930. 14.95 s [vm-test-run-centjes-e2e-test] client # [ 1.786957] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100
  931. 14.95 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.054538] ACPI: \_SB_.LNKB: Enabled at IRQ 11
  932. 14.95 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.057421] hub 1-0:1.0: USB hub found
  933. 14.96 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.059762] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 lpm-pol 0
  934. 14.96 s [vm-test-run-centjes-e2e-test] client # [ 1.797017] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.12
  935. 14.96 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.063454] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0
  936. 14.96 s [vm-test-run-centjes-e2e-test] client # [ 1.800875] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
  937. 14.97 s [vm-test-run-centjes-e2e-test] client # [ 1.804379] usb usb1: Product: UHCI Host Controller
  938. 14.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.070424] hub 1-0:1.0: 2 ports detected
  939. 14.97 s [vm-test-run-centjes-e2e-test] client # [ 1.806821] usb usb1: Manufacturer: Linux 6.12.62 uhci_hcd
  940. 14.97 s [vm-test-run-centjes-e2e-test] client # [ 1.809823] usb usb1: SerialNumber: 0000:00:01.2
  941. 14.98 s [vm-test-run-centjes-e2e-test] client # [ 1.821353] ACPI: \_SB_.LNKA: Enabled at IRQ 10
  942. 14.99 s [vm-test-run-centjes-e2e-test] client # [ 1.826922] scsi host0: ata_piix
  943. 14.99 s [vm-test-run-centjes-e2e-test] client # [ 1.833421] scsi host1: ata_piix
  944. 15.00 s [vm-test-run-centjes-e2e-test] client # [ 1.838121] ACPI: \_SB_.LNKB: Enabled at IRQ 11
  945. 15.01 s [vm-test-run-centjes-e2e-test] client # [ 1.840764] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 lpm-pol 0
  946. 15.01 s [vm-test-run-centjes-e2e-test] client # [ 1.844692] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0
  947. 15.01 s [vm-test-run-centjes-e2e-test] client # [ 1.850535] hub 1-0:1.0: USB hub found
  948. 15.02 s [vm-test-run-centjes-e2e-test] client # [ 1.853002] hub 1-0:1.0: 2 ports detected
  949. 15.12 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.224269] ata2: found unknown device (class 0)
  950. 15.13 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.227750] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
  951. 15.13 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.233120] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5
  952. 15.17 s [vm-test-run-centjes-e2e-test] client # [ 2.008286] ata2: found unknown device (class 0)
  953. 15.17 s [vm-test-run-centjes-e2e-test] client # [ 2.011975] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
  954. 15.18 s [vm-test-run-centjes-e2e-test] client # [ 2.017181] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5
  955. 15.19 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.295254] usb 1-1: new full-speed USB device number 2 using uhci_hcd
  956. 15.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.337940] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
  957. 15.24 s [vm-test-run-centjes-e2e-test] client # [ 2.080089] usb 1-1: new full-speed USB device number 2 using uhci_hcd
  958. 15.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.356693] virtio_blk virtio5: 1/0/0 default/read/poll queues
  959. 15.28 s [vm-test-run-centjes-e2e-test] client # [ 2.112861] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
  960. 15.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.389373] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)
  961. 15.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.397259] netfs: FS-Cache loaded
  962. 15.31 s [vm-test-run-centjes-e2e-test] client # [ 2.145371] virtio_blk virtio5: 1/0/0 default/read/poll queues
  963. 15.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.417194] 9pnet: Installing 9P2000 support
  964. 15.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.420918] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
  965. 15.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.424414] cdrom: Uniform CD-ROM driver Revision: 3.20
  966. 15.34 s [vm-test-run-centjes-e2e-test] client # [ 2.175954] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)
  967. 15.34 s [vm-test-run-centjes-e2e-test] client # [ 2.184149] netfs: FS-Cache loaded
  968. 15.35 s [vm-test-run-centjes-e2e-test] client # [ 2.190847] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
  969. 15.36 s [vm-test-run-centjes-e2e-test] client # [ 2.194175] cdrom: Uniform CD-ROM driver Revision: 3.20
  970. 15.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.469303] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00
  971. 15.37 s [vm-test-run-centjes-e2e-test] client # [ 2.207192] 9pnet: Installing 9P2000 support
  972. 15.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.473371] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10
  973. 15.38 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.476924] usb 1-1: Product: QEMU USB Tablet
  974. 15.38 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.479291] usb 1-1: Manufacturer: QEMU
  975. 15.39 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.481267] usb 1-1: SerialNumber: 28754-0000:00:01.2-1
  976. 15.41 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.510147] hid: raw HID events driver (C) Jiri Kosina
  977. 15.42 s [vm-test-run-centjes-e2e-test] client # [ 2.254959] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00
  978. 15.42 s [vm-test-run-centjes-e2e-test] client # [ 2.259757] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10
  979. 15.43 s [vm-test-run-centjes-e2e-test] client # [ 2.264041] usb 1-1: Product: QEMU USB Tablet
  980. 15.43 s [vm-test-run-centjes-e2e-test] client # [ 2.267133] usb 1-1: Manufacturer: QEMU
  981. 15.43 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.535334] usbcore: registered new interface driver usbhid
  982. 15.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.539096] usbhid: USB HID core driver
  983. 15.44 s [vm-test-run-centjes-e2e-test] client # [ 2.270022] usb 1-1: SerialNumber: 28754-0000:00:01.2-1
  984. 15.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.554576] 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
  985. 15.46 s [vm-test-run-centjes-e2e-test] client # [ 2.296528] hid: raw HID events driver (C) Jiri Kosina
  986. 15.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.563231] 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
  987. 15.48 s [vm-test-run-centjes-e2e-test] client # [ 2.321772] usbcore: registered new interface driver usbhid
  988. 15.49 s [vm-test-run-centjes-e2e-test] client # [ 2.324723] usbhid: USB HID core driver
  989. 15.50 s [vm-test-run-centjes-e2e-test] client # [ 2.338959] 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
  990. 15.52 s [vm-test-run-centjes-e2e-test] client # [ 2.352194] 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
  991. 15.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.833573] EXT4-fs (vda): mounted filesystem 3a328118-91e8-468d-8e77-5e707df778c4 r/w with ordered data mode. Quota mode: none.
  992. 15.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.873909] 9p: Installing v9fs 9p2000 file system support
  993. 15.79 s [vm-test-run-centjes-e2e-test] client # [ 2.623831] EXT4-fs (vda): mounted filesystem fbe26b78-2ebe-4421-88bf-5b0b2fc8302f r/w with ordered data mode. Quota mode: none.
  994. 15.83 s [vm-test-run-centjes-e2e-test] client # [ 2.664556] 9p: Installing v9fs 9p2000 file system support
  995. 16.18 s [vm-test-run-centjes-e2e-test] docsserver # [ 3.277221] EXT4-fs (vda): re-mounted 3a328118-91e8-468d-8e77-5e707df778c4.
  996. 16.18 s [vm-test-run-centjes-e2e-test] docsserver # [ 3.283806] booting system configuration /nix/store/s2hzr4dfcc5kw5va4694x014sn949jw6-nixos-system-docsserver-test
  997. 16.20 s [vm-test-run-centjes-e2e-test] client # [ 3.033746] EXT4-fs (vda): re-mounted fbe26b78-2ebe-4421-88bf-5b0b2fc8302f.
  998. 16.20 s [vm-test-run-centjes-e2e-test] client # [ 3.039542] booting system configuration /nix/store/001k9r4jinw78ih5x5hxggb82ynlbkqn-nixos-system-client-test
  999. 18.23 s [vm-test-run-centjes-e2e-test] client # [ 5.071518] systemd[1]: Inserted module 'autofs4'
  1000. 18.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.336126] systemd[1]: Inserted module 'autofs4'
  1001. 18.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.388464] 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)
  1002. 18.30 s [vm-test-run-centjes-e2e-test] client # [ 5.123728] 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)
  1003. 18.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.402509] systemd[1]: Detected virtualization kvm.
  1004. 18.31 s [vm-test-run-centjes-e2e-test] client # [ 5.139083] systemd[1]: Detected virtualization kvm.
  1005. 18.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.405354] systemd[1]: Detected architecture x86-64.
  1006. 18.31 s [vm-test-run-centjes-e2e-test] client # [ 5.141561] systemd[1]: Detected architecture x86-64.
  1007. 18.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.408288] systemd[1]: Detected first boot.
  1008. 18.32 s [vm-test-run-centjes-e2e-test] client # [ 5.144097] systemd[1]: Detected first boot.
  1009. 18.32 s [vm-test-run-centjes-e2e-test] client # [ 5.153050] systemd[1]: Initializing machine ID from random generator.
  1010. 18.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.417622] systemd[1]: Initializing machine ID from random generator.
  1011. 18.35 s [vm-test-run-centjes-e2e-test] client # [ 5.187318] systemd[1]: Hostname set to <client>.
  1012. 18.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.458801] systemd[1]: Hostname set to <docsserver>.
  1013. 18.47 s [vm-test-run-centjes-e2e-test] client # [ 5.310581] systemd[1]: bpf-restrict-fs: LSM BPF program attached
  1014. 18.47 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.578179] systemd[1]: bpf-restrict-fs: LSM BPF program attached
  1015. 18.63 s [vm-test-run-centjes-e2e-test] client # [ 5.468900] systemd[1]: Applying preset policy.
  1016. 18.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.740662] systemd[1]: Applying preset policy.
  1017. 19.20 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.302159] systemd[1]: Populated /etc with preset unit settings.
  1018. 19.22 s [vm-test-run-centjes-e2e-test] client # [ 6.060675] systemd[1]: Populated /etc with preset unit settings.
  1019. 19.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.738321] systemd[1]: /etc/systemd/system/centjes-docs-site-production.service:3: Invalid URL, ignoring: /nix/store/1k7yhzxm0gw4zdphzhbmllxgwj34zjfr-settings-check
  1020. 19.75 s [vm-test-run-centjes-e2e-test] client # [ 6.588775] systemd[1]: Queued start job for default target Multi-User System.
  1021. 19.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.853624] systemd[1]: Queued start job for default target Multi-User System.
  1022. 19.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.885248] systemd[1]: Created slice Slice /system/getty.
  1023. 19.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.888408] systemd[1]: Created slice Slice /system/modprobe.
  1024. 19.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.891499] systemd[1]: Created slice User and Session Slice.
  1025. 19.79 s [vm-test-run-centjes-e2e-test] client # [ 6.624986] systemd[1]: Created slice Slice /system/getty.
  1026. 19.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.894427] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
  1027. 19.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.897939] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
  1028. 19.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.901465] systemd[1]: Expecting device /dev/hvc0...
  1029. 19.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.903817] systemd[1]: Expecting device /dev/ttyS0...
  1030. 19.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.906166] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...
  1031. 19.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.909255] systemd[1]: Reached target Local Encrypted Volumes.
  1032. 19.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.911743] systemd[1]: Reached target Virtual Machines and Containers.
  1033. 19.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.914389] systemd[1]: Reached target Path Units.
  1034. 19.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.916387] systemd[1]: Reached target Remote File Systems.
  1035. 19.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.918596] systemd[1]: Reached target Slice Units.
  1036. 19.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.920630] systemd[1]: Reached target Swaps.
  1037. 19.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.926924] systemd[1]: Listening on Process Core Dump Socket.
  1038. 19.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.933362] systemd[1]: Listening on Credential Encryption/Decryption.
  1039. 19.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.936410] systemd[1]: Listening on Journal Audit Socket.
  1040. 19.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.938876] systemd[1]: Listening on Journal Socket (/dev/log).
  1041. 19.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.941496] systemd[1]: Listening on Journal Sockets.
  1042. 19.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.944284] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.
  1043. 19.84 s [vm-test-run-centjes-e2e-test] client # [ 6.679313] systemd[1]: Created slice Slice /system/modprobe.
  1044. 19.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.947741] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
  1045. 19.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.953382] systemd[1]: Listening on udev Control Socket.
  1046. 19.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.957954] systemd[1]: Listening on udev Kernel Socket.
  1047. 19.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.965719] systemd[1]: Mounting Huge Pages File System...
  1048. 19.86 s [vm-test-run-centjes-e2e-test] client # [ 6.703226] systemd[1]: Created slice User and Session Slice.
  1049. 19.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.972338] systemd[1]: Mounting POSIX Message Queue File System...
  1050. 19.87 s [vm-test-run-centjes-e2e-test] client # [ 6.711168] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
  1051. 19.88 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.983467] systemd[1]: Mounting Kernel Debug File System...
  1052. 19.88 s [vm-test-run-centjes-e2e-test] client # [ 6.718833] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
  1053. 19.89 s [vm-test-run-centjes-e2e-test] client # [ 6.726577] systemd[1]: Expecting device /dev/hvc0...
  1054. 19.89 s [vm-test-run-centjes-e2e-test] client # [ 6.728765] systemd[1]: Expecting device /dev/ttyS0...
  1055. 19.89 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.998644] systemd[1]: Mounting Kernel Trace File System...
  1056. 19.90 s [vm-test-run-centjes-e2e-test] client # [ 6.730827] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...
  1057. 19.90 s [vm-test-run-centjes-e2e-test] client # [ 6.737840] systemd[1]: Reached target Local Encrypted Volumes.
  1058. 19.91 s [vm-test-run-centjes-e2e-test] client # [ 6.745304] systemd[1]: Reached target Virtual Machines and Containers.
  1059. 19.91 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.012277] systemd[1]: Starting Create List of Static Device Nodes...
  1060. 19.91 s [vm-test-run-centjes-e2e-test] client # [ 6.747919] systemd[1]: Reached target Path Units.
  1061. 19.92 s [vm-test-run-centjes-e2e-test] client # [ 6.754429] systemd[1]: Reached target Remote File Systems.
  1062. 19.92 s [vm-test-run-centjes-e2e-test] client # [ 6.761045] systemd[1]: Reached target Slice Units.
  1063. 19.92 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.024852] systemd[1]: Load Kernel Module 9pnet_virtio was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!9pnet_virtio).
  1064. 19.93 s [vm-test-run-centjes-e2e-test] client # [ 6.763201] systemd[1]: Reached target Swaps.
  1065. 19.93 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.038706] systemd[1]: Starting Load Kernel Module configfs...
  1066. 19.94 s [vm-test-run-centjes-e2e-test] client # [ 6.778994] systemd[1]: Listening on Process Core Dump Socket.
  1067. 19.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.046542] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm).
  1068. 19.95 s [vm-test-run-centjes-e2e-test] client # [ 6.784449] systemd[1]: Listening on Credential Encryption/Decryption.
  1069. 19.95 s [vm-test-run-centjes-e2e-test] client # [ 6.788251] systemd[1]: Listening on Journal Audit Socket.
  1070. 19.95 s [vm-test-run-centjes-e2e-test] client # [ 6.790796] systemd[1]: Listening on Journal Socket (/dev/log).
  1071. 19.95 s [vm-test-run-centjes-e2e-test] client # [ 6.793453] systemd[1]: Listening on Journal Sockets.
  1072. 19.96 s [vm-test-run-centjes-e2e-test] client # [ 6.796434] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.
  1073. 19.96 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.061891] systemd[1]: Starting Load Kernel Module efi_pstore...
  1074. 19.96 s [vm-test-run-centjes-e2e-test] client # [ 6.799817] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
  1075. 19.97 s [vm-test-run-centjes-e2e-test] client # [ 6.804322] systemd[1]: Listening on udev Control Socket.
  1076. 19.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.070572] systemd[1]: Starting Load Kernel Module fuse...
  1077. 19.97 s [vm-test-run-centjes-e2e-test] client # [ 6.806774] systemd[1]: Listening on udev Kernel Socket.
  1078. 19.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.075567] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
  1079. 19.98 s [vm-test-run-centjes-e2e-test] client # [ 6.813715] systemd[1]: Mounting Huge Pages File System...
  1080. 19.98 s [vm-test-run-centjes-e2e-test] client # [ 6.820372] systemd[1]: Mounting POSIX Message Queue File System...
  1081. 19.99 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.084467] 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).
  1082. 19.99 s [vm-test-run-centjes-e2e-test] client # [ 6.829277] systemd[1]: Mounting Kernel Debug File System...
  1083. 20.00 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.102923] systemd[1]: Starting Journal Service...
  1084. 20.00 s [vm-test-run-centjes-e2e-test] client # [ 6.842383] systemd[1]: Mounting Kernel Trace File System...
  1085. 20.01 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.111215] systemd[1]: Starting Load Kernel Modules...
  1086. 20.02 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.121185] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...
  1087. 20.03 s [vm-test-run-centjes-e2e-test] client # [ 6.866576] systemd[1]: Starting Create List of Static Device Nodes...
  1088. 20.03 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.133123] systemd[1]: Starting Remount Root and Kernel File Systems...
  1089. 20.04 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.139204] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
  1090. 20.04 s [vm-test-run-centjes-e2e-test] client # [ 6.877536] systemd[1]: Load Kernel Module 9pnet_virtio was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!9pnet_virtio).
  1091. 20.05 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.157392] systemd[1]: Starting Coldplug All udev Devices...
  1092. 20.05 s [vm-test-run-centjes-e2e-test] client # [ 6.894086] systemd[1]: Starting Load Kernel Module configfs...
  1093. 20.06 s [vm-test-run-centjes-e2e-test] client # [ 6.899084] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm).
  1094. 20.06 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.168895] systemd[1]: Mounted Huge Pages File System.
  1095. 20.07 s [vm-test-run-centjes-e2e-test] client # [ 6.908871] systemd[1]: Starting Load Kernel Module efi_pstore...
  1096. 20.07 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.173794] systemd[1]: Mounted POSIX Message Queue File System.
  1097. 20.08 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.179394] systemd[1]: Mounted Kernel Debug File System.
  1098. 20.08 s [vm-test-run-centjes-e2e-test] client # [ 6.918727] systemd[1]: Starting Load Kernel Module fuse...
  1099. 20.08 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.184776] systemd[1]: Mounted Kernel Trace File System.
  1100. 20.09 s [vm-test-run-centjes-e2e-test] client # [ 6.924013] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
  1101. 20.09 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.193238] systemd[1]: Finished Create List of Static Device Nodes.
  1102. 20.10 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.199582] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
  1103. 20.10 s [vm-test-run-centjes-e2e-test] client # [ 6.933550] 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).
  1104. 20.10 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.206926] systemd[1]: Finished Load Kernel Module efi_pstore.
  1105. 20.11 s [vm-test-run-centjes-e2e-test] client # [ 6.951567] systemd[1]: Starting Journal Service...
  1106. 20.12 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.222217] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...
  1107. 20.12 s [vm-test-run-centjes-e2e-test] client # [ 6.959431] systemd[1]: Starting Load Kernel Modules...
  1108. 20.13 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.229400] systemd[1]: modprobe@configfs.service: Deactivated successfully.
  1109. 20.13 s [vm-test-run-centjes-e2e-test] client # [ 6.969556] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...
  1110. 20.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.237484] systemd[1]: Finished Load Kernel Module configfs.
  1111. 20.14 s [vm-test-run-centjes-e2e-test] client # [ 6.982258] systemd[1]: Starting Remount Root and Kernel File Systems...
  1112. 20.15 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.249175] systemd[1]: Mounting Kernel Configuration File System...
  1113. 20.15 s [vm-test-run-centjes-e2e-test] client # [ 6.987772] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
  1114. 20.16 s [vm-test-run-centjes-e2e-test] client # [ 7.001839] systemd[1]: Starting Coldplug All udev Devices...
  1115. 20.18 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.279641] systemd[1]: Mounted Kernel Configuration File System.
  1116. 20.18 s [vm-test-run-centjes-e2e-test] client # [ 7.016122] systemd[1]: Mounted Huge Pages File System.
  1117. 20.18 s [vm-test-run-centjes-e2e-test] client # [ 7.021176] systemd[1]: Mounted POSIX Message Queue File System.
  1118. 20.19 s [vm-test-run-centjes-e2e-test] client # [ 7.026551] systemd[1]: Mounted Kernel Debug File System.
  1119. 20.19 s [vm-test-run-centjes-e2e-test] client # [ 7.031927] systemd[1]: Mounted Kernel Trace File System.
  1120. 20.20 s [vm-test-run-centjes-e2e-test] client # [ 7.040041] systemd[1]: Finished Create List of Static Device Nodes.
  1121. 20.21 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.312747] fuse: init (API version 7.41)
  1122. 20.21 s [vm-test-run-centjes-e2e-test] client # [ 7.051913] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...
  1123. 20.22 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.323256] EXT4-fs (vda): re-mounted 3a328118-91e8-468d-8e77-5e707df778c4.
  1124. 20.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.332436] systemd[1]: modprobe@fuse.service: Deactivated successfully.
  1125. 20.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.338843] systemd[1]: Finished Load Kernel Module fuse.
  1126. 20.24 s [vm-test-run-centjes-e2e-test] client # [ 7.080166] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
  1127. 20.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.348531] systemd[1]: Mounting FUSE Control File System...
  1128. 20.25 s [vm-test-run-centjes-e2e-test] client # [ 7.086374] systemd[1]: Finished Load Kernel Module efi_pstore.
  1129. 20.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.357400] loop: module loaded
  1130. 20.26 s [vm-test-run-centjes-e2e-test] client # [ 7.093067] systemd[1]: modprobe@configfs.service: Deactivated successfully.
  1131. 20.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.364113] systemd[1]: Finished Remount Root and Kernel File Systems.
  1132. 20.26 s [vm-test-run-centjes-e2e-test] client # [ 7.099846] systemd[1]: Finished Load Kernel Module configfs.
  1133. 20.27 s [vm-test-run-centjes-e2e-test] client # [ 7.110725] systemd[1]: Mounting Kernel Configuration File System...
  1134. 20.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.373542] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
  1135. 20.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.391791] systemd[1]: Starting Load/Save OS Random Seed...
  1136. 20.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.396134] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
  1137. 20.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.400263] systemd-journald[391]: Collecting audit messages is enabled.
  1138. 20.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.408968] systemd[1]: Finished Load Kernel Modules.
  1139. 20.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.414543] systemd[1]: Mounted FUSE Control File System.
  1140. 20.31 s [vm-test-run-centjes-e2e-test] client # [ 7.150466] systemd[1]: Mounted Kernel Configuration File System.
  1141. 20.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.427283] systemd[1]: Starting Firewall...
  1142. 20.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.435224] systemd[1]: Starting Apply Kernel Variables...
  1143. 20.33 s [vm-test-run-centjes-e2e-test] client # [ 7.174041] EXT4-fs (vda): re-mounted fbe26b78-2ebe-4421-88bf-5b0b2fc8302f.
  1144. 20.35 s [vm-test-run-centjes-e2e-test] client # [ 7.186023] fuse: init (API version 7.41)
  1145. 20.35 s [vm-test-run-centjes-e2e-test] client # [ 7.188963] loop: module loaded
  1146. 20.36 s [vm-test-run-centjes-e2e-test] client # [ 7.203218] systemd[1]: modprobe@fuse.service: Deactivated successfully.
  1147. 20.37 s [vm-test-run-centjes-e2e-test] client # [ 7.209805] systemd[1]: Finished Load Kernel Module fuse.
  1148. 20.37 s [vm-test-run-centjes-e2e-test] client # [ 7.212395] systemd-journald[390]: Collecting audit messages is enabled.
  1149. 20.38 s [vm-test-run-centjes-e2e-test] client # [ 7.217817] systemd[1]: Finished Remount Root and Kernel File Systems.
  1150. 20.39 s [vm-test-run-centjes-e2e-test] client # [ 7.223429] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
  1151. 20.40 s [vm-test-run-centjes-e2e-test] client # [ 7.239792] systemd[1]: Starting Load/Save OS Random Seed...
  1152. 20.41 s [vm-test-run-centjes-e2e-test] client # [ 7.245783] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
  1153. 20.42 s [vm-test-run-centjes-e2e-test] client # [ 7.259938] systemd[1]: Finished Load Kernel Modules.
  1154. 20.43 s [vm-test-run-centjes-e2e-test] client # [ 7.269276] systemd[1]: Starting Firewall...
  1155. 20.43 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.533411] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
  1156. 20.44 s [vm-test-run-centjes-e2e-test] client # [ 7.275874] systemd[1]: Starting Apply Kernel Variables...
  1157. 20.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.547795] systemd[1]: Starting Create Static Device Nodes in /dev...
  1158. 20.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.558196] systemd[1]: Finished Load/Save OS Random Seed.
  1159. 20.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.562957] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.
  1160. 20.47 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.571203] systemd[1]: Reached target First Boot Complete.
  1161. 20.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.616474] systemd[1]: Finished Apply Kernel Variables.
  1162. 20.52 s [vm-test-run-centjes-e2e-test] client # [ 7.360542] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
  1163. 20.53 s [vm-test-run-centjes-e2e-test] client # [ 7.373909] systemd[1]: Starting Create Static Device Nodes in /dev...
  1164. 20.56 s [vm-test-run-centjes-e2e-test] client # [ 7.400516] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.
  1165. 20.58 s [vm-test-run-centjes-e2e-test] client # [ 7.416803] systemd[1]: Finished Load/Save OS Random Seed.
  1166. 20.59 s [vm-test-run-centjes-e2e-test] client # [ 7.424374] systemd[1]: Reached target First Boot Complete.
  1167. 20.61 s [vm-test-run-centjes-e2e-test] client # [ 7.447178] systemd[1]: Finished Apply Kernel Variables.
  1168. 20.62 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.721232] systemd[1]: Finished Create Static Device Nodes in /dev.
  1169. 20.62 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.726558] systemd[1]: Reached target Preparation for Local File Systems.
  1170. 20.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.736349] systemd[1]: Starting Rule-based Manager for Device Events and Files...
  1171. 20.70 s [vm-test-run-centjes-e2e-test] client # [ 7.541454] systemd[1]: Finished Create Static Device Nodes in /dev.
  1172. 20.71 s [vm-test-run-centjes-e2e-test] client # [ 7.547291] systemd[1]: Reached target Preparation for Local File Systems.
  1173. 20.72 s [vm-test-run-centjes-e2e-test] client # [ 7.556745] systemd[1]: Starting Rule-based Manager for Device Events and Files...
  1174. 20.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.930673] systemd[1]: Started Journal Service.
  1175. 20.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.129472] systemd-modules-load[392]: Inserted module 'loop'
  1176. 20.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.140896] systemd-oomd[393]: [0;1;38:5:185mNo swap; memory pressure usage will be degraded
  1177. 20.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.155552] systemd-udevd[416]: Using default interface naming scheme 'v258'.
  1178. 20.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.170389] systemd[1]: Starting Flush Journal to Persistent Storage...
  1179. 20.92 s [vm-test-run-centjes-e2e-test] client # [ 7.760100] systemd[1]: Started Journal Service.
  1180. 20.93 s [vm-test-run-centjes-e2e-test] client # [ 7.274381] systemd-modules-load[391]: Inserted module 'loop'
  1181. 20.95 s [vm-test-run-centjes-e2e-test] client # [ 7.288261] systemd-oomd[392]: [0;1;38:5:185mNo swap; memory pressure usage will be degraded
  1182. 20.95 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.054505] systemd-journald[391]: Received client request to flush runtime journal.
  1183. 20.96 s [vm-test-run-centjes-e2e-test] client # [ 7.305234] systemd-udevd[414]: Using default interface naming scheme 'v258'.
  1184. 20.97 s [vm-test-run-centjes-e2e-test] client # [ 7.319166] systemd[1]: Starting Flush Journal to Persistent Storage...
  1185. 21.03 s [vm-test-run-centjes-e2e-test] client # [ 7.872041] systemd-journald[390]: Received client request to flush runtime journal.
  1186. 21.22 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.508815] systemd[1]: Finished Coldplug All udev Devices.
  1187. 21.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.531323] systemd[1]: Started Rule-based Manager for Device Events and Files.
  1188. 21.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.548214] systemd[1]: Mounting /run/wrappers...
  1189. 21.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.555700] systemd[1]: Mounted /run/wrappers.
  1190. 21.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.562672] systemd[1]: Reached target Local File Systems.
  1191. 21.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.571937] systemd[1]: Listening on Boot Entries Service Socket.
  1192. 21.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.578740] systemd[1]: Starting Create SUID/SGID Wrappers...
  1193. 21.29 s [vm-test-run-centjes-e2e-test] client # [ 7.630903] systemd[1]: Finished Coldplug All udev Devices.
  1194. 21.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.588605] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.
  1195. 21.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.603437] systemd[1]: Starting Save Transient machine-id to Disk...
  1196. 21.31 s [vm-test-run-centjes-e2e-test] client # [ 7.656159] systemd[1]: Started Rule-based Manager for Device Events and Files.
  1197. 21.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.613416] systemd[1]: Finished Flush Journal to Persistent Storage.
  1198. 21.32 s [vm-test-run-centjes-e2e-test] client # [ 7.668650] systemd[1]: Mounting /run/wrappers...
  1199. 21.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.622760] systemd[1]: Starting Create System Files and Directories...
  1200. 21.34 s [vm-test-run-centjes-e2e-test] client # [ 7.678896] systemd[1]: Mounting FUSE Control File System...
  1201. 21.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.637259] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
  1202. 21.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.645199] systemd[1]: Finished Save Transient machine-id to Disk.
  1203. 21.35 s [vm-test-run-centjes-e2e-test] client # [ 7.693851] systemd[1]: Mounted FUSE Control File System.
  1204. 21.36 s [vm-test-run-centjes-e2e-test] client # [ 7.705839] systemd[1]: Mounted /run/wrappers.
  1205. 21.38 s [vm-test-run-centjes-e2e-test] client # [ 7.720389] systemd[1]: Reached target Local File Systems.
  1206. 21.38 s [vm-test-run-centjes-e2e-test] client # [ 7.731684] systemd[1]: Listening on Boot Entries Service Socket.
  1207. 21.39 s [vm-test-run-centjes-e2e-test] client # [ 7.739925] systemd[1]: Starting Create SUID/SGID Wrappers...
  1208. 21.41 s [vm-test-run-centjes-e2e-test] client # [ 7.748130] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.
  1209. 21.42 s [vm-test-run-centjes-e2e-test] client # [ 7.764342] systemd[1]: Starting Save Transient machine-id to Disk...
  1210. 21.43 s [vm-test-run-centjes-e2e-test] client # [ 7.772277] systemd[1]: Finished Flush Journal to Persistent Storage.
  1211. 21.43 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.725327] systemd[1]: Finished Create System Files and Directories.
  1212. 21.44 s [vm-test-run-centjes-e2e-test] client # [ 7.781414] systemd[1]: Starting Create System Files and Directories...
  1213. 21.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.739420] systemd[1]: Starting Rebuild Journal Catalog...
  1214. 21.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.752603] systemd[1]: Starting Record System Boot/Shutdown in UTMP...
  1215. 21.49 s [vm-test-run-centjes-e2e-test] client # [ 7.831827] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
  1216. 21.50 s [vm-test-run-centjes-e2e-test] client # [ 7.846592] systemd[1]: Finished Save Transient machine-id to Disk.
  1217. 21.52 s [vm-test-run-centjes-e2e-test] client # [ 7.863845] systemd[1]: Finished Create System Files and Directories.
  1218. 21.53 s [vm-test-run-centjes-e2e-test] client # [ 7.873906] systemd[1]: Starting Rebuild Journal Catalog...
  1219. 21.54 s [vm-test-run-centjes-e2e-test] client # [ 7.885304] systemd[1]: Starting Record System Boot/Shutdown in UTMP...
  1220. 21.55 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.842396] systemd[1]: Finished Record System Boot/Shutdown in UTMP.
  1221. 21.61 s [vm-test-run-centjes-e2e-test] client # [ 7.961918] systemd[1]: Finished Record System Boot/Shutdown in UTMP.
  1222. 21.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.926181] systemd[1]: Finished Rebuild Journal Catalog.
  1223. 21.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.934963] systemd[1]: Starting Update is Completed...
  1224. 21.70 s [vm-test-run-centjes-e2e-test] client # [ 8.041368] systemd[1]: Finished Rebuild Journal Catalog.
  1225. 21.70 s [vm-test-run-centjes-e2e-test] client # [ 8.048458] systemd[1]: Starting Update is Completed...
  1226. 21.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.016796] systemd[1]: Finished Update is Completed.
  1227. 21.77 s [vm-test-run-centjes-e2e-test] client # [ 8.121292] systemd[1]: Finished Update is Completed.
  1228. 21.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.263432] systemd[1]: Found device /dev/hvc0.
  1229. 22.00 s [vm-test-run-centjes-e2e-test] client # [ 8.346354] systemd[1]: Found device /dev/hvc0.
  1230. 22.01 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.310769] systemd[1]: Found device /dev/ttyS0.
  1231. 22.03 s [vm-test-run-centjes-e2e-test] client # [ 8.375246] systemd[1]: Found device /dev/ttyS0.
  1232. 22.06 s [vm-test-run-centjes-e2e-test] client # [ 8.402742] (udev-worker)[516]: Network interface NamePolicy= disabled on kernel command line.
  1233. 22.06 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.350532] (udev-worker)[516]: [0;1;38:5:185meth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
  1234. 22.08 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.367449] (udev-worker)[516]: Network interface NamePolicy= disabled on kernel command line.
  1235. 22.09 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.382165] (udev-worker)[523]: Network interface NamePolicy= disabled on kernel command line.
  1236. 22.09 s [vm-test-run-centjes-e2e-test] client # [ 8.430947] (udev-worker)[507]: [0;1;38:5:185meth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
  1237. 22.10 s [vm-test-run-centjes-e2e-test] client # [ 8.443458] (udev-worker)[507]: Network interface NamePolicy= disabled on kernel command line.
  1238. 22.12 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.411217] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.
  1239. 22.13 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.421595] systemd[1]: Finished Create SUID/SGID Wrappers.
  1240. 22.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.435198] systemd[1]: Reached target System Initialization.
  1241. 22.16 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.443908] systemd[1]: Started Discard unused filesystem blocks once a week.
  1242. 22.18 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.459510] systemd[1]: Started Daily Cleanup of Temporary Directories.
  1243. 22.20 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.482812] systemd[1]: Reached target Timer Units.
  1244. 22.20 s [vm-test-run-centjes-e2e-test] client # [ 8.539978] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.
  1245. 22.21 s [vm-test-run-centjes-e2e-test] client # [ 8.552390] systemd[1]: Finished Create SUID/SGID Wrappers.
  1246. 22.22 s [vm-test-run-centjes-e2e-test] client # [ 8.561368] systemd[1]: Reached target System Initialization.
  1247. 22.22 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.499980] systemd[1]: Listening on D-Bus System Message Bus Socket.
  1248. 22.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.527156] systemd[1]: Listening on Nix Daemon Socket.
  1249. 22.24 s [vm-test-run-centjes-e2e-test] client # [ 8.573863] systemd[1]: Started Discard unused filesystem blocks once a week.
  1250. 22.25 s [vm-test-run-centjes-e2e-test] client # [ 8.590992] systemd[1]: Started Daily Cleanup of Temporary Directories.
  1251. 22.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.536994] systemd[1]: Listening on Hostname Service Socket.
  1252. 22.27 s [vm-test-run-centjes-e2e-test] client # [ 8.607689] systemd[1]: Reached target Timer Units.
  1253. 22.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.564290] systemd[1]: Reached target Socket Units.
  1254. 22.28 s [vm-test-run-centjes-e2e-test] client # [ 8.624666] systemd[1]: Listening on D-Bus System Message Bus Socket.
  1255. 22.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.582298] systemd[1]: Reached target Basic System.
  1256. 22.30 s [vm-test-run-centjes-e2e-test] client # [ 8.636696] systemd[1]: Listening on Nix Daemon Socket.
  1257. 22.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.593784] systemd[1]: System is tainted: support-ended
  1258. 22.31 s [vm-test-run-centjes-e2e-test] client # [ 8.650444] systemd[1]: Listening on Hostname Service Socket.
  1259. 22.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.617577] systemd[1]: Started backdoor.service.
  1260. 22.34 s [vm-test-run-centjes-e2e-test] client # [ 8.674771] systemd[1]: Reached target Socket Units.
  1261. 22.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.637379] systemd[1]: Starting Import lastlog data into lastlog2 database...
  1262. 22.35 s [vm-test-run-centjes-e2e-test] client # [ 8.694223] systemd[1]: Reached target Basic System.
  1263. 22.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.650599] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
  1264. 22.37 s [vm-test-run-centjes-e2e-test] docsserver # connecting to host...
  1265. 22.37 s [vm-test-run-centjes-e2e-test] client # [ 8.710740] systemd[1]: System is tainted: support-ended
  1266. 22.39 s [vm-test-run-centjes-e2e-test] client # [ 8.733251] systemd[1]: Started backdoor.service.
  1267. 22.39 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.676827] systemd[1]: Started Reset console on configuration changes.
  1268. 22.41 s [vm-test-run-centjes-e2e-test] client # [ 8.746950] systemd[1]: Starting Import lastlog data into lastlog2 database...
  1269. 22.41 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.699497] systemd[1]: Starting resolvconf update...
  1270. 22.42 s [vm-test-run-centjes-e2e-test] client # [ 8.767288] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
  1271. 22.42 s [vm-test-run-centjes-e2e-test] client # connecting to host...
  1272. 22.43 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.717764] systemd[1]: Started Centjes docs site production Service.
  1273. 22.44 s [vm-test-run-centjes-e2e-test] docsserver: Guest shell says: b'Spawning backdoor root shell...\n'
  1274. 22.46 s [vm-test-run-centjes-e2e-test] docsserver: connected to guest root shell
  1275. 22.46 s [vm-test-run-centjes-e2e-test] docsserver: (connecting took 10.34 seconds)
  1276. 22.46 s [vm-test-run-centjes-e2e-test] docsserver: (finished: waiting for the VM to finish booting, in 10.34 seconds)
  1277. 22.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.735741] systemd[1]: Starting D-Bus System Message Bus...
  1278. 22.46 s [vm-test-run-centjes-e2e-test] client # [ 8.784583] systemd[1]: Started Reset console on configuration changes.
  1279. 22.46 s [vm-test-run-centjes-e2e-test] client # [ 8.798441] systemd[1]: Starting resolvconf update...
  1280. 22.47 s [vm-test-run-centjes-e2e-test] client # [ 8.814775] systemd[1]: Starting D-Bus System Message Bus...
  1281. 22.48 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.749531] nsncd[616]: Aug 31 16:17:51.053 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
  1282. 22.49 s [vm-test-run-centjes-e2e-test] client # [ 8.832133] systemd[1]: Finished Import lastlog data into lastlog2 database.
  1283. 22.50 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.776918] systemd[1]: Finished Import lastlog data into lastlog2 database.
  1284. 22.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.797786] systemd[1]: Started Name Service Cache Daemon (nsncd).
  1285. 22.52 s [vm-test-run-centjes-e2e-test] client # [ 8.846866] nsncd[620]: Aug 31 16:17:51.465 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
  1286. 22.53 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.817776] systemd[1]: Reached target Host and Network Name Lookups.
  1287. 22.54 s [vm-test-run-centjes-e2e-test] client # [ 8.878857] systemd[1]: Started Name Service Cache Daemon (nsncd).
  1288. 22.54 s [vm-test-run-centjes-e2e-test] client # [ 8.887601] systemd[1]: Reached target Host and Network Name Lookups.
  1289. 22.55 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.833571] dbus-daemon[621]: dbus[621]: Unknown username "systemd-timesync" in message bus configuration file
  1290. 22.56 s [vm-test-run-centjes-e2e-test] client # [ 8.899778] dbus-daemon[623]: dbus[623]: Unknown username "systemd-timesync" in message bus configuration file
  1291. 22.57 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.856616] systemd[1]: Reached target User and Group Name Lookups.
  1292. 22.57 s [vm-test-run-centjes-e2e-test] client # [ 8.917110] systemd[1]: Reached target User and Group Name Lookups.
  1293. 22.58 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.867764] systemd[1]: Starting User Login Management...
  1294. 22.58 s [vm-test-run-centjes-e2e-test] client # [ 8.924491] systemd[1]: Starting User Login Management...
  1295. 22.58 s [vm-test-run-centjes-e2e-test] client # [ 8.931587] systemd[1]: Finished Firewall.
  1296. 22.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.883155] systemd[1]: Finished Firewall.
  1297. 22.61 s [vm-test-run-centjes-e2e-test] client # [ 8.958162] systemd[1]: Started D-Bus System Message Bus.
  1298. 22.62 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.910926] systemd[1]: Started D-Bus System Message Bus.
  1299. 22.63 s [vm-test-run-centjes-e2e-test] client # [ 8.982193] systemd[1]: Found device Virtio network device.
  1300. 22.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.952752] systemd[1]: Found device Virtio network device.
  1301. 22.66 s [vm-test-run-centjes-e2e-test] client # [ 8.999666] systemd[1]: Stopped target Host and Network Name Lookups.
  1302. 22.68 s [vm-test-run-centjes-e2e-test] client # [ 9.015955] systemd[1]: Stopping Host and Network Name Lookups...
  1303. 22.68 s [vm-test-run-centjes-e2e-test] client # [ 9.030206] systemd[1]: Stopped target User and Group Name Lookups.
  1304. 22.69 s [vm-test-run-centjes-e2e-test] client # [ 9.036457] systemd[1]: Stopping User and Group Name Lookups...
  1305. 22.70 s [vm-test-run-centjes-e2e-test] client # [ 9.044291] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...
  1306. 22.71 s [vm-test-run-centjes-e2e-test] client # [ 9.052429] systemd[1]: nscd.service: Deactivated successfully.
  1307. 22.71 s [vm-test-run-centjes-e2e-test] client # [ 9.059298] systemd[1]: Stopped Name Service Cache Daemon (nsncd).
  1308. 22.72 s [vm-test-run-centjes-e2e-test] client # [ 9.066600] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
  1309. 22.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.024092] systemd-logind[652]: New seat seat0.
  1310. 22.74 s [vm-test-run-centjes-e2e-test] client # [ 9.081932] systemd-logind[651]: New seat seat0.
  1311. 22.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.038459] systemd[1]: Started User Login Management.
  1312. 22.75 s [vm-test-run-centjes-e2e-test] client # [ 9.092548] systemd[1]: Started User Login Management.
  1313. 22.76 s [vm-test-run-centjes-e2e-test] client # [ 9.103726] systemd[1]: Starting linger-users.service...
  1314. 22.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.051532] systemd[1]: Stopped target Host and Network Name Lookups.
  1315. 22.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.067392] systemd[1]: Stopping Host and Network Name Lookups...
  1316. 22.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.083921] systemd[1]: Stopped target User and Group Name Lookups.
  1317. 22.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.096142] systemd[1]: Stopping User and Group Name Lookups...
  1318. 22.80 s [vm-test-run-centjes-e2e-test] client # [ 9.137930] nsncd[690]: Aug 31 16:17:51.784 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
  1319. 22.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.108188] systemd[1]: Starting linger-users.service...
  1320. 22.82 s [vm-test-run-centjes-e2e-test] client # [ 9.162368] systemd[1]: Started Name Service Cache Daemon (nsncd).
  1321. 22.82 s [vm-test-run-centjes-e2e-test] client # [ 9.169940] systemd[1]: Reached target Host and Network Name Lookups.
  1322. 22.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.118272] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...
  1323. 22.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.127960] systemd[1]: nscd.service: Deactivated successfully.
  1324. 22.84 s [vm-test-run-centjes-e2e-test] client # [ 9.177697] systemd[1]: Reached target User and Group Name Lookups.
  1325. 22.84 s [vm-test-run-centjes-e2e-test] client # [ 9.188570] systemd[1]: linger-users.service: Deactivated successfully.
  1326. 22.85 s [vm-test-run-centjes-e2e-test] client # [ 9.194575] systemd[1]: Finished linger-users.service.
  1327. 22.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.139489] systemd[1]: Stopped Name Service Cache Daemon (nsncd).
  1328. 22.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.148996] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
  1329. 22.87 s [vm-test-run-centjes-e2e-test] client # [ 9.217551] systemd[1]: Finished resolvconf update.
  1330. 22.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.166411] systemd[1]: linger-users.service: Deactivated successfully.
  1331. 22.88 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.176241] systemd[1]: Finished linger-users.service.
  1332. 22.88 s [vm-test-run-centjes-e2e-test] client # [ 9.225852] systemd[1]: Reached target Preparation for Network.
  1333. 22.89 s [vm-test-run-centjes-e2e-test] client # [ 9.235987] systemd[1]: Starting DHCP Client...
  1334. 22.90 s [vm-test-run-centjes-e2e-test] client # [ 9.244608] systemd[1]: Starting Address configuration of eth1...
  1335. 22.92 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.211562] nsncd[706]: Aug 31 16:17:51.551 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
  1336. 22.93 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.226483] systemd[1]: Started Name Service Cache Daemon (nsncd).
  1337. 22.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.233580] systemd[1]: Reached target Host and Network Name Lookups.
  1338. 22.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.240277] systemd[1]: Reached target User and Group Name Lookups.
  1339. 22.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.268566] systemd[1]: Finished resolvconf update.
  1340. 22.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.280384] systemd[1]: Reached target Preparation for Network.
  1341. 22.99 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.288344] systemd[1]: Starting DHCP Client...
  1342. 23.00 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.292782] systemd[1]: Starting Address configuration of eth1...
  1343. 23.03 s [vm-test-run-centjes-e2e-test] client # [ 9.375890] network-addresses-eth1-start[720]: adding address 192.168.1.1/24... done
  1344. 23.06 s [vm-test-run-centjes-e2e-test] client # [ 9.400646] network-addresses-eth1-start[720]: adding address 2001:db8:1::1/64... done
  1345. 23.08 s [vm-test-run-centjes-e2e-test] client # [ 9.430512] systemd[1]: Finished Address configuration of eth1.[ 9.923784] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
  1346. 23.09 s [vm-test-run-centjes-e2e-test] client #
  1347. 23.09 s [vm-test-run-centjes-e2e-test] client # [ 9.441481] systemd[1]: Starting Networking Setup...
  1348. 23.12 s [vm-test-run-centjes-e2e-test] client # [ 9.963063] mousedev: PS/2 mouse device common for all mice
  1349. 23.14 s [vm-test-run-centjes-e2e-test] client # [ 9.486202] dhcpcd[727]: dhcpcd-10.2.4 starting
  1350. 23.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.435805] network-addresses-eth1-start[738]: adding address 192.168.1.2/24... done
  1351. 23.15 s [vm-test-run-centjes-e2e-test] client # [ 9.502142] dhcpcd[739]: dev: loaded udev
  1352. 23.16 s [vm-test-run-centjes-e2e-test] client # [ 9.995489] ACPI: button: Power Button [PWRF]
  1353. 23.17 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.461926] network-addresses-eth1-start[738]: adding address 2001:db8:1::2/64... done
  1354. 23.17 s [vm-test-run-centjes-e2e-test] client # [ 10.013321] 8021q: 802.1Q VLAN Support v1.8
  1355. 23.18 s [vm-test-run-centjes-e2e-test] client # [ 10.017680] 8021q: adding VLAN 0 to HW filter on device eth1
  1356. 23.18 s [vm-test-run-centjes-e2e-test] client # [ 10.020090] rtc_cmos 00:05: RTC can wake from S4
  1357. 23.18 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.285369] mousedev: PS/2 mouse device common for all mice
  1358. 23.20 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.497507] systemd[1]: Finished Address configuration of eth1.
  1359. 23.20 s [vm-test-run-centjes-e2e-test] client # [ 10.044140] rtc_cmos 00:05: registered as rtc0
  1360. 23.21 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.504534] systemd[1]: Starting Networking Setup...[ 10.311665] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
  1361. 23.21 s [vm-test-run-centjes-e2e-test] client # [ 10.048977] parport_pc 00:03: reported by Plug and Play ACPI
  1362. 23.21 s [vm-test-run-centjes-e2e-test] docsserver #
  1363. 23.22 s [vm-test-run-centjes-e2e-test] client # [ 10.056782] Floppy drive(s): fd0 is 2.88M AMI BIOS
  1364. 23.23 s [vm-test-run-centjes-e2e-test] client # [ 10.068908] rtc_cmos 00:05: setting system clock to 2026-08-31T16:17:52 UTC (1788193072)
  1365. 23.24 s [vm-test-run-centjes-e2e-test] client # [ 10.081839] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]
  1366. 23.26 s [vm-test-run-centjes-e2e-test] client # [ 10.103782] FDC 0 is a S82078B
  1367. 23.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.369903] ACPI: button: Power Button [PWRF]
  1368. 23.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.568680] dhcpcd[745]: dhcpcd-10.2.4 starting
  1369. 23.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.584397] dhcpcd[758]: dev: loaded udev
  1370. 23.29 s [vm-test-run-centjes-e2e-test] client # [ 10.126105] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs
  1371. 23.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.411728] 8021q: 802.1Q VLAN Support v1.8
  1372. 23.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.415644] 8021q: adding VLAN 0 to HW filter on device eth1
  1373. 23.31 s [vm-test-run-centjes-e2e-test] client # [ 10.152990] cfg80211: Loading compiled-in X.509 certificates for regulatory database
  1374. 23.35 s [vm-test-run-centjes-e2e-test] client # [ 10.185302] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
  1375. 23.35 s [vm-test-run-centjes-e2e-test] client # [ 10.188900] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
  1376. 23.36 s [vm-test-run-centjes-e2e-test] client # [ 10.195937] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
  1377. 23.36 s [vm-test-run-centjes-e2e-test] client # [ 10.202017] cfg80211: failed to load regulatory.db
  1378. 23.38 s [vm-test-run-centjes-e2e-test] client # [ 9.727172] systemd[1]: Finished Networking Setup.
  1379. 23.38 s [vm-test-run-centjes-e2e-test] client # [ 9.731147] systemd[1]: Reached target Network.
  1380. 23.39 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.492702] rtc_cmos 00:05: RTC can wake from S4
  1381. 23.39 s [vm-test-run-centjes-e2e-test] client # [ 9.737545] systemd[1]: Starting Permit User Sessions...
  1382. 23.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.548974] cfg80211: Loading compiled-in X.509 certificates for regulatory database
  1383. 23.45 s [vm-test-run-centjes-e2e-test] client # [ 9.793605] systemd[1]: Finished Permit User Sessions.
  1384. 23.45 s [vm-test-run-centjes-e2e-test] client # [ 9.802681] systemd[1]: Started Getty on tty1.
  1385. 23.46 s [vm-test-run-centjes-e2e-test] client # [ 9.808264] systemd[1]: Reached target Login Prompts.
  1386. 23.47 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.577932] Floppy drive(s): fd0 is 2.88M AMI BIOS
  1387. 23.48 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.580857] rtc_cmos 00:05: registered as rtc0
  1388. 23.48 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.587147] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
  1389. 23.48 s [vm-test-run-centjes-e2e-test] client # [ 10.324740] 8021q: adding VLAN 0 to HW filter on device eth0
  1390. 23.49 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.590594] parport_pc 00:03: reported by Plug and Play ACPI
  1391. 23.49 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.592840] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
  1392. 23.49 s [vm-test-run-centjes-e2e-test] client # [ 9.841175] dhcpcd[739]: eth0: waiting for carrier
  1393. 23.49 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.596291] rtc_cmos 00:05: setting system clock to 2026-08-31T16:17:52 UTC (1788193072)
  1394. 23.50 s [vm-test-run-centjes-e2e-test] client # [ 9.844695] dhcpcd[739]: libudev: received NULL device[ 10.336295] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
  1395. 23.50 s [vm-test-run-centjes-e2e-test] client #
  1396. 23.50 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.601547] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
  1397. 23.50 s [vm-test-run-centjes-e2e-test] client # [ 9.849706] dhcpcd[739]: libudev: received NULL device
  1398. 23.50 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.606595] cfg80211: failed to load regulatory.db
  1399. 23.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.611567] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]
  1400. 23.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.621357] FDC 0 is a S82078B
  1401. 23.52 s [vm-test-run-centjes-e2e-test] client # [ 10.358828] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD
  1402. 23.53 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.822576] systemd[1]: Finished Networking Setup.
  1403. 23.53 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.830178] systemd[1]: Reached target Network.
  1404. 23.53 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.637651] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs
  1405. 23.54 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.835736] systemd[1]: Starting Permit User Sessions...
  1406. 23.55 s [vm-test-run-centjes-e2e-test] client # [ 10.386249] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input4
  1407. 23.56 s [vm-test-run-centjes-e2e-test] client # [ 10.399286] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console
  1408. 23.56 s [vm-test-run-centjes-e2e-test] client # [ 10.403736] cryptd: max_cpu_qlen set to 1000
  1409. 23.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.896525] systemd[1]: Finished Permit User Sessions.
  1410. 23.61 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.903441] systemd[1]: Started Getty on tty1.
  1411. 23.61 s [vm-test-run-centjes-e2e-test] client # [ 10.430538] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6
  1412. 23.61 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.908214] systemd[1]: Reached target Login Prompts.
  1413. 23.62 s [vm-test-run-centjes-e2e-test] client # [ 10.435194] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5
  1414. 23.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.734622] 8021q: adding VLAN 0 to HW filter on device eth0
  1415. 23.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.934403] dhcpcd[758]: eth0: waiting for carrier
  1416. 23.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.937222] dhcpcd[758]: libudev: received NULL device
  1417. 23.64 s [vm-test-run-centjes-e2e-test] client # [ 10.445540] AES CTR mode by8 optimization enabled
  1418. 23.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.940961] dhcpcd[758]: libudev: received NULL device
  1419. 23.65 s [vm-test-run-centjes-e2e-test] client # [ 10.447174] Console: switching to colour dummy device 80x25
  1420. 23.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.764095] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
  1421. 23.66 s [vm-test-run-centjes-e2e-test] client # [ 10.503094] [drm] Found bochs VGA, ID 0xb0c5.
  1422. 23.67 s [vm-test-run-centjes-e2e-test] client # [ 10.504092] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.
  1423. 23.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.798792] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD
  1424. 23.70 s [vm-test-run-centjes-e2e-test] client # [ 10.545932] [drm] Found EDID data blob.
  1425. 23.71 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.812350] cryptd: max_cpu_qlen set to 1000
  1426. 23.71 s [vm-test-run-centjes-e2e-test] client # [ 10.058908] systemd[1]: Starting Virtual Console Setup...
  1427. 23.72 s [vm-test-run-centjes-e2e-test] client # [ 10.560568] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0
  1428. 23.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.814658] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input4
  1429. 23.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.832402] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6
  1430. 23.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.835733] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5
  1431. 23.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.850504] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console
  1432. 23.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.865974] Console: switching to colour dummy device 80x25
  1433. 23.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.867851] [drm] Found bochs VGA, ID 0xb0c5.
  1434. 23.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.868895] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.
  1435. 23.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.875473] [drm] Found EDID data blob.
  1436. 23.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.876608] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0
  1437. 23.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.882582] AES CTR mode by8 optimization enabled
  1438. 24.32 s [vm-test-run-centjes-e2e-test] client # [ 10.111630] systemd-logind[651]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
  1439. 24.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.636507] dhcpcd[758]: eth0: carrier acquired
  1440. 24.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.658686] dhcpcd[758]: DUID 00:01:00:01:32:28:65:b1:52:54:00:12:34:56
  1441. 24.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.661459] dhcpcd[758]: eth0: IAID 00:12:34:56
  1442. 24.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.663839] dhcpcd[758]: eth0: adding address fe80::5054:ff:fe12:3456
  1443. 24.48 s [vm-test-run-centjes-e2e-test] client # [ 10.607737] fbcon: bochs-drmdrmfb (fb0) is primary device
  1444. 24.48 s [vm-test-run-centjes-e2e-test] client # [ 11.183937] Console: switching to colour frame buffer device 160x50
  1445. 24.49 s [vm-test-run-centjes-e2e-test] client # [ 11.325757] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device
  1446. 24.49 s [vm-test-run-centjes-e2e-test] client # [ 10.674389] dhcpcd[739]: eth0: carrier acquired
  1447. 24.49 s [vm-test-run-centjes-e2e-test] client # [ 10.839349] dhcpcd[739]: DUID 00:01:00:01:32:28:65:b1:52:54:00:12:34:56
  1448. 24.49 s [vm-test-run-centjes-e2e-test] client # [ 10.841521] systemd-logind[651]: Watching system buttons on /dev/input/event2 (Power Button)
  1449. 24.49 s [vm-test-run-centjes-e2e-test] client # [ 10.844005] dhcpcd[739]: eth0: IAID 00:12:34:56
  1450. 24.50 s [vm-test-run-centjes-e2e-test] client # [ 10.845947] dhcpcd[739]: eth0: adding address fe80::5054:ff:fe12:3456
  1451. 24.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.886644] fbcon: bochs-drmdrmfb (fb0) is primary device
  1452. 24.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.479783] Console: switching to colour frame buffer device 160x50
  1453. 24.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.620188] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device
  1454. 24.67 s [vm-test-run-centjes-e2e-test] client # [ 11.016889] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
  1455. 24.78 s [vm-test-run-centjes-e2e-test] client # [ 11.573501] kvm_amd: TSC scaling supported
  1456. 24.78 s [vm-test-run-centjes-e2e-test] client # [ 11.574431] kvm_amd: Nested Virtualization enabled
  1457. 24.78 s [vm-test-run-centjes-e2e-test] client # [ 11.575456] kvm_amd: Nested Paging enabled
  1458. 24.78 s [vm-test-run-centjes-e2e-test] client # [ 11.576384] kvm_amd: LBR virtualization supported
  1459. 24.78 s [vm-test-run-centjes-e2e-test] client # [ 11.583427] ppdev: user-space parallel port driver
  1460. 24.78 s [vm-test-run-centjes-e2e-test] client # [ 11.604895] kvm_amd: Virtual VMLOAD VMSAVE supported
  1461. 24.78 s [vm-test-run-centjes-e2e-test] client # [ 11.606074] kvm_amd: Virtual GIF supported
  1462. 24.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.091670] systemd[1]: Starting Virtual Console Setup...
  1463. 24.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.137377] systemd-logind[652]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
  1464. 24.85 s [vm-test-run-centjes-e2e-test] client # [ 11.199434] systemd-logind[651]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)
  1465. 24.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.152302] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
  1466. 24.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.162129] systemd-logind[652]: Watching system buttons on /dev/input/event2 (Power Button)
  1467. 24.90 s [vm-test-run-centjes-e2e-test] client # [ 11.243279] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
  1468. 24.90 s [vm-test-run-centjes-e2e-test] docsserver # [ 12.008904] ppdev: user-space parallel port driver
  1469. 24.90 s [vm-test-run-centjes-e2e-test] client # [ 11.252823] systemd[1]: Stopped Virtual Console Setup.
  1470. 24.91 s [vm-test-run-centjes-e2e-test] client # [ 11.262969] systemd[1]: Starting Virtual Console Setup...
  1471. 24.93 s [vm-test-run-centjes-e2e-test] client # [ 11.773715] EDAC MC: Ver: 3.0.0
  1472. 24.96 s [vm-test-run-centjes-e2e-test] client # [ 11.305541] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
  1473. 24.96 s [vm-test-run-centjes-e2e-test] client # [ 11.315168] systemd[1]: Stopped Virtual Console Setup.
  1474. 24.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 12.076200] kvm_amd: TSC scaling supported
  1475. 24.97 s [vm-test-run-centjes-e2e-test] client # [ 11.321819] systemd[1]: Starting Virtual Console Setup...
  1476. 24.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 12.077256] kvm_amd: Nested Virtualization enabled
  1477. 24.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 12.078464] kvm_amd: Nested Paging enabled
  1478. 24.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 12.079572] kvm_amd: LBR virtualization supported
  1479. 25.03 s [vm-test-run-centjes-e2e-test] docsserver # [ 12.133485] kvm_amd: Virtual VMLOAD VMSAVE supported
  1480. 25.03 s [vm-test-run-centjes-e2e-test] docsserver # [ 12.134634] kvm_amd: Virtual GIF supported
  1481. 25.09 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.382113] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
  1482. 25.09 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.391808] systemd[1]: Stopped Virtual Console Setup.
  1483. 25.10 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.398347] systemd-logind[652]: Watching system buttons on /dev/input/event5 (QEMU Virtio Keyboard)
  1484. 25.11 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.406509] systemd[1]: Starting Virtual Console Setup...
  1485. 25.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.434163] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
  1486. 25.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.441322] systemd[1]: Stopped Virtual Console Setup.
  1487. 25.15 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.445751] systemd[1]: Starting Virtual Console Setup...
  1488. 25.16 s [vm-test-run-centjes-e2e-test] docsserver # [ 12.266465] EDAC MC: Ver: 3.0.0
  1489. 25.25 s [vm-test-run-centjes-e2e-test] client # [ 11.597090] dhcpcd[739]: eth0: soliciting a DHCP lease
  1490. 25.27 s [vm-test-run-centjes-e2e-test] client # [ 12.110793] NET: Registered PF_PACKET protocol family
  1491. 25.28 s [vm-test-run-centjes-e2e-test] client # [ 11.631869] dhcpcd[739]: eth0: offered 10.0.2.15 from 10.0.2.2
  1492. 25.29 s [vm-test-run-centjes-e2e-test] client # [ 11.640326] dhcpcd[739]: eth0: probing address 10.0.2.15/24
  1493. 25.39 s [vm-test-run-centjes-e2e-test] client # [ 11.740095] systemd[1]: Finished Virtual Console Setup.
  1494. 25.57 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.872164] systemd[1]: Finished Virtual Console Setup.
  1495. 26.07 s [vm-test-run-centjes-e2e-test] docsserver # [ 12.364806] dhcpcd[758]: eth0: soliciting a DHCP lease
  1496. 26.09 s [vm-test-run-centjes-e2e-test] docsserver # [ 13.192546] NET: Registered PF_PACKET protocol family
  1497. 26.10 s [vm-test-run-centjes-e2e-test] docsserver # [ 12.395800] dhcpcd[758]: eth0: offered 10.0.2.15 from 10.0.2.2
  1498. 26.10 s [vm-test-run-centjes-e2e-test] docsserver # [ 12.399372] dhcpcd[758]: eth0: probing address 10.0.2.15/24
  1499. 26.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 12.640457] dhcpcd[758]: eth0: soliciting an IPv6 router
  1500. 26.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 12.642373] dhcpcd[758]: eth0: Router Advertisement from fe80::2
  1501. 26.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 12.644414] dhcpcd[758]: eth0: adding address fec0::5054:ff:fe12:3456/64
  1502. 26.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 12.646484] dhcpcd[758]: eth0: adding route to fec0::/64
  1503. 26.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 12.648237] dhcpcd[758]: eth0: adding default route via fe80::2
  1504. 26.51 s [vm-test-run-centjes-e2e-test] client # [ 12.858553] dhcpcd[739]: eth0: soliciting an IPv6 router
  1505. 26.51 s [vm-test-run-centjes-e2e-test] client # [ 12.861294] dhcpcd[739]: eth0: Router Advertisement from fe80::2
  1506. 26.52 s [vm-test-run-centjes-e2e-test] client # [ 12.867972] dhcpcd[739]: eth0: adding address fec0::5054:ff:fe12:3456/64
  1507. 26.52 s [vm-test-run-centjes-e2e-test] client # [ 12.869981] dhcpcd[739]: eth0: adding route to fec0::/64
  1508. 26.53 s [vm-test-run-centjes-e2e-test] client # [ 12.871756] dhcpcd[739]: eth0: adding default route via fe80::2
  1509. 30.01 s [vm-test-run-centjes-e2e-test] client # [ 16.356441] dhcpcd[739]: eth0: leased 10.0.2.15 for 86400 seconds
  1510. 30.03 s [vm-test-run-centjes-e2e-test] client # [ 16.359225] dhcpcd[739]: eth0: adding route to 10.0.2.0/24
  1511. 30.03 s [vm-test-run-centjes-e2e-test] client # [ 16.365643] dhcpcd[739]: eth0: adding default route via 10.0.2.2
  1512. 30.13 s [vm-test-run-centjes-e2e-test] client # [ 16.480932] systemd[1]: Started DHCP Client.
  1513. 30.14 s [vm-test-run-centjes-e2e-test] client # [ 16.486168] systemd[1]: Reached target Multi-User System.
  1514. 30.14 s [vm-test-run-centjes-e2e-test] client # [ 16.492144] systemd[1]: Startup finished in 4.524s (kernel) + 11.958s (userspace) = 16.483s.
  1515. 31.20 s [vm-test-run-centjes-e2e-test] docsserver # [ 17.501466] dhcpcd[758]: eth0: leased 10.0.2.15 for 86400 seconds
  1516. 31.20 s [vm-test-run-centjes-e2e-test] docsserver # [ 17.503779] dhcpcd[758]: eth0: adding route to 10.0.2.0/24
  1517. 31.21 s [vm-test-run-centjes-e2e-test] docsserver # [ 17.505877] dhcpcd[758]: eth0: adding default route via 10.0.2.2
  1518. 31.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 17.636596] systemd[1]: Started DHCP Client.
  1519. 31.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 17.643246] systemd[1]: Reached target Multi-User System.
  1520. 31.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 17.646250] systemd[1]: Startup finished in 4.474s (kernel) + 13.166s (userspace) = 17.640s.
  1521. 32.51 s [vm-test-run-centjes-e2e-test] docsserver: (finished: waiting for unit default.target, in 20.39 seconds)
  1522. 32.51 s [vm-test-run-centjes-e2e-test] client: waiting for unit default.target
  1523. 32.51 s [vm-test-run-centjes-e2e-test] client: waiting for the VM to finish booting
  1524. 32.51 s [vm-test-run-centjes-e2e-test] client: Guest shell says: b'Spawning backdoor root shell...\n'
  1525. 32.51 s [vm-test-run-centjes-e2e-test] client: connected to guest root shell
  1526. 32.51 s [vm-test-run-centjes-e2e-test] client: (connecting took 0.00 seconds)
  1527. 32.51 s [vm-test-run-centjes-e2e-test] client: (finished: waiting for the VM to finish booting, in 0.00 seconds)
  1528. 32.64 s [vm-test-run-centjes-e2e-test] client: (finished: waiting for unit default.target, in 0.13 seconds)
  1529. 32.71 s [vm-test-run-centjes-e2e-test] docsserver: waiting for unit centjes-docs-site-production.service
  1530. 32.76 s [vm-test-run-centjes-e2e-test] docsserver: (finished: waiting for unit centjes-docs-site-production.service, in 0.05 seconds)
  1531. 32.76 s [vm-test-run-centjes-e2e-test] docsserver: waiting for unit default.target
  1532. 32.82 s [vm-test-run-centjes-e2e-test] docsserver: (finished: waiting for unit default.target, in 0.05 seconds)
  1533. 32.82 s [vm-test-run-centjes-e2e-test] docsserver: waiting for TCP port 8001 on localhost
  1534. 32.91 s [vm-test-run-centjes-e2e-test] docsserver # Connection to localhost (127.0.0.1) 8001 port [tcp/vcom-tunnel] succeeded!
  1535. 32.91 s [vm-test-run-centjes-e2e-test] docsserver: (finished: waiting for TCP port 8001 on localhost, in 0.09 seconds)
  1536. 32.91 s [vm-test-run-centjes-e2e-test] client: must succeed: curl docsserver:8001
  1537. 33.02 s [vm-test-run-centjes-e2e-test] client # % Total % Received % Xferd Average Speed Time Time Time Current
  1538. 33.04 s [vm-test-run-centjes-e2e-test] client # Dload Upload Total Spent Left Speed
  1539. 33.07 s [vm-test-run-centjes-e2e-test] docsserver # [ 19.372392] centjes-docs-site-production-start[635]: 192.168.1.1 - - [31/Aug/2026:16:18:02 +0000] "GET / HTTP/1.1" 200 3768 "" "curl/8.17.0"
  1540. 33.09 s [vm-test-run-centjes-e2e-test] client # 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0100 3768 100 3768 0 0 46720 0 --:--:-- --:--:-- --:--:-- 47696
  1541. 33.10 s [vm-test-run-centjes-e2e-test] client: (finished: must succeed: curl docsserver:8001, in 0.20 seconds)
  1542. 33.10 s [vm-test-run-centjes-e2e-test] (finished: run the VM test script, in 21.72 seconds)
  1543. 33.25 s [vm-test-run-centjes-e2e-test] test script finished in 21.87s
  1544. 33.25 s [vm-test-run-centjes-e2e-test] cleanup
  1545. 33.25 s [vm-test-run-centjes-e2e-test] kill machine (pid 31)
  1546. 33.25 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)
  1547. 33.27 s [vm-test-run-centjes-e2e-test] kill machine (pid 9)
  1548. 33.27 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)
  1549. 33.28 s [vm-test-run-centjes-e2e-test] kill vlan (pid 7)
  1550. 33.28 s [vm-test-run-centjes-e2e-test] (finished: cleanup, in 0.03 seconds)
  1551. 33.42 s [vm-test-run-centjes-e2e-test:post-build] Uploading to cachix cache "centjes": /nix/store/5br3fk5608sjani3zpgd5ph3w3kzv6cs-vm-test-run-centjes-e2e-test
  1552. 33.79 s [vm-test-run-centjes-e2e-test:post-build] Pushing 1 paths (0 are already present) using zstd to cache centjes ⏳
  1553. 33.79 s [vm-test-run-centjes-e2e-test:post-build]
  1554. 34.15 s [vm-test-run-centjes-e2e-test:post-build] Pushing /nix/store/5br3fk5608sjani3zpgd5ph3w3kzv6cs-vm-test-run-centjes-e2e-test (96.00 B)
  1555. 35.03 s [vm-test-run-centjes-e2e-test:post-build]
  1556. 35.03 s [vm-test-run-centjes-e2e-test:post-build] All done.
  1557. 35.04 s [vm-test-run-centjes-e2e-test:post-build] Uploading to the NixCI staging cache: /nix/store/5br3fk5608sjani3zpgd5ph3w3kzv6cs-vm-test-run-centjes-e2e-test
  1558. 35.08 s [vm-test-run-centjes-e2e-test:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
  1559. 35.09 s [vm-test-run-centjes-e2e-test:post-build] copying 1 paths...
  1560. 35.09 s [vm-test-run-centjes-e2e-test:post-build] copying path '/nix/store/5br3fk5608sjani3zpgd5ph3w3kzv6cs-vm-test-run-centjes-e2e-test' to 'https://cache.staging.nix-ci.com'...
  1561. 35.20 s [vm-test-run-centjes-e2e-test:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
  1562. 35.50 s [vm-test-run-centjes-e2e-test:post-build] copying 0 paths...
  1563. 35.53 s Uploaded vm-test-run-centjes-e2e-test in 2.1s
  1564. 35.53 s Progress: 1 of 2 built, 11 of 11 downloaded from cache
  1565. 35.53 s Built vm-test-run-centjes-e2e-test in 25.3s
  1566. 35.53 s Progress: 2 of 2 built, 11 of 11 downloaded from cache
  1567. 35.53 s /nix/store/5br3fk5608sjani3zpgd5ph3w3kzv6cs-vm-test-run-centjes-e2e-test
  1568. 35.69 s Build succeeded.
More Logs Raw Logs

Agent Instructions

Wait for completion, without logs:

curl --netrc -N https://staging.nix-ci.com/gh:NorfairKing:centjes/master/1687d2a96d1c0f4c6369882d6ee7b6e1bd10a2ff/ce46b446-2f4a-4ff2-9202-86556948a92b/wait

Latest logs, with timestamps (streams while running):

curl --netrc -N https://staging.nix-ci.com/gh:NorfairKing:centjes/master/1687d2a96d1c0f4c6369882d6ee7b6e1bd10a2ff/ce46b446-2f4a-4ff2-9202-86556948a92b/tail

All logs, with timestamps (available after completion):

curl --netrc https://staging.nix-ci.com/gh:NorfairKing:centjes/master/1687d2a96d1c0f4c6369882d6ee7b6e1bd10a2ff/ce46b446-2f4a-4ff2-9202-86556948a92b/logs

Raw logs, from the start (no timestamps):

curl --netrc -N https://staging.nix-ci.com/gh:NorfairKing:centjes/master/1687d2a96d1c0f4c6369882d6ee7b6e1bd10a2ff/ce46b446-2f4a-4ff2-9202-86556948a92b/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/master/1687d2a96d1c0f4c6369882d6ee7b6e1bd10a2ff/ce46b446-2f4a-4ff2-9202-86556948a92b/logs

Reproduce locally: https://staging.nix-ci.com/gh:NorfairKing:centjes/master/1687d2a96d1c0f4c6369882d6ee7b6e1bd10a2ff/ce46b446-2f4a-4ff2-9202-86556948a92b/repro

This page is also available in machine-friendly formats. Request one with the Accept header:

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