build checks.x86_64-linux.e2e-test

Reproduce this run
  1. 0.02 s $ /nix/store/vzx1mi9c0xfadmsm9dhd83d005cb1qs9-coreutils-9.8/bin/timeout --kill-after=15s 7200s /nix/store/99b1z08awpxj8b6mzggn59gp1shljnff-nix-2.34.5/bin/nix --extra-experimental-features nix-command --extra-experimental-features flakes --log-format internal-json build --no-link git+https://github.com/NorfairKing/centjes?ref=fix-price-change-not-counted-in-average&rev=ac5d1a5f099cac7cf5a8619e4f02fa69a4308bdd#checks.x86_64-linux.e2e-test --print-build-logs
  2. 0.07 s warning: ignoring untrusted flake configuration setting 'extra-substituters'.
  3. 0.07 s Pass '--accept-flake-config' to trust it
  4. 0.07 s warning: ignoring untrusted flake configuration setting 'extra-trusted-public-keys'.
  5. 0.07 s Pass '--accept-flake-config' to trust it
  6. 0.07 s
  7. 0.86 s Substituting settings-check from https://cache.staging.nix-ci.com
  8. 0.86 s Substituting unit-script-centjes-docs-site-production-start from https://cache.staging.nix-ci.com
  9. 0.88 s Progress: 0 of 2 built, 1 of 10 fetched (1 fetching)
  10. 0.88 s Progress: 0 of 2 built, 2 of 10 fetched
  11. 0.88 s Substituting unit-centjes-docs-site-production.service from https://cache.staging.nix-ci.com
  12. 0.90 s Progress: 0 of 2 built, 3 of 10 fetched
  13. 0.90 s Substituting system-units from https://cache.staging.nix-ci.com
  14. 0.93 s Progress: 0 of 2 built, 4 of 10 fetched
  15. 0.93 s Substituting etc from https://cache.staging.nix-ci.com
  16. 0.95 s Progress: 0 of 2 built, 5 of 10 fetched
  17. 0.95 s Substituting nixos-system-docsserver-test from https://cache.staging.nix-ci.com
  18. 0.97 s Progress: 0 of 2 built, 6 of 10 fetched
  19. 0.97 s Substituting closure-info from https://cache.staging.nix-ci.com
  20. 1.00 s Progress: 0 of 2 built, 7 of 10 fetched
  21. 1.00 s Substituting run-nixos-vm from https://cache.staging.nix-ci.com
  22. 1.01 s Progress: 0 of 2 built, 8 of 10 fetched
  23. 1.01 s Substituting nixos-vm from https://cache.staging.nix-ci.com
  24. 1.03 s Progress: 0 of 2 built, 9 of 10 fetched
  25. 1.03 s Substituting nixos-test-driver-centjes-e2e-test from https://cache.staging.nix-ci.com
  26. 1.04 s Progress: 0 of 2 built, 10 of 10 fetched
  27. 1.16 s Building vm-test-run-centjes-e2e-test
  28. 1.49 s [vm-test-run-centjes-e2e-test] Machine state will be reset. To keep it, pass --keep-vm-state
  29. 1.49 s [vm-test-run-centjes-e2e-test] start all VLans
  30. 1.49 s [vm-test-run-centjes-e2e-test] start vlan
  31. 1.49 s [vm-test-run-centjes-e2e-test] running vlan (pid 7; ctl /build/vde1.ctl)
  32. 1.49 s [vm-test-run-centjes-e2e-test] (finished: start all VLans, in 0.00 seconds)
  33. 1.49 s [vm-test-run-centjes-e2e-test] Test will time out and terminate in 3600 seconds
  34. 1.49 s [vm-test-run-centjes-e2e-test] run the VM test script
  35. 1.49 s [vm-test-run-centjes-e2e-test] additionally exposed symbols:
  36. 1.49 s [vm-test-run-centjes-e2e-test] client, docsserver,
  37. 1.49 s [vm-test-run-centjes-e2e-test] vlan1,
  38. 1.49 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
  39. 1.49 s [vm-test-run-centjes-e2e-test] docsserver: starting vm
  40. 1.51 s [vm-test-run-centjes-e2e-test] mke2fs 1.47.3 (8-Jul-2025)
  41. 1.64 s [vm-test-run-centjes-e2e-test] docsserver: QEMU running (pid 9)
  42. 1.64 s [vm-test-run-centjes-e2e-test] docsserver # Disk image does not exist, creating the virtualisation disk image...
  43. 1.64 s [vm-test-run-centjes-e2e-test] docsserver # Formatting '/build/vm-state-docsserver/tmp.SFZeZ5lYWC', fmt=raw size=1073741824
  44. 1.64 s [vm-test-run-centjes-e2e-test] docsserver # Discarding device blocks: 0/262144 done
  45. 1.64 s [vm-test-run-centjes-e2e-test] client: starting vm
  46. 1.64 s [vm-test-run-centjes-e2e-test] docsserver # Creating filesystem with 262144 4k blocks and 65536 inodes
  47. 1.64 s [vm-test-run-centjes-e2e-test] docsserver # Filesystem UUID: 6639912a-c4ee-41cf-8fed-fac43081e889
  48. 1.64 s [vm-test-run-centjes-e2e-test] docsserver # Superblock backups stored on blocks:
  49. 1.64 s [vm-test-run-centjes-e2e-test] docsserver # 32768, 98304, 163840, 229376
  50. 1.64 s [vm-test-run-centjes-e2e-test] docsserver #
  51. 1.64 s [vm-test-run-centjes-e2e-test] docsserver # Allocating group tables: 0/8 done
  52. 1.64 s [vm-test-run-centjes-e2e-test] docsserver # Writing inode tables: 0/8 done
  53. 1.64 s [vm-test-run-centjes-e2e-test] docsserver # Creating journal (8192 blocks): done
  54. 1.64 s [vm-test-run-centjes-e2e-test] docsserver # Writing superblocks and filesystem accounting information: 0/8 done
  55. 1.64 s [vm-test-run-centjes-e2e-test] docsserver #
  56. 1.64 s [vm-test-run-centjes-e2e-test] docsserver # Virtualisation disk image created.
  57. 1.66 s [vm-test-run-centjes-e2e-test] mke2fs 1.47.3 (8-Jul-2025)
  58. 1.74 s [vm-test-run-centjes-e2e-test] docsserver # c[?7lSeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)
  59. 1.77 s [vm-test-run-centjes-e2e-test] client: QEMU running (pid 31)
  60. 1.77 s [vm-test-run-centjes-e2e-test] client # Disk image does not exist, creating the virtualisation disk image...
  61. 1.77 s [vm-test-run-centjes-e2e-test] client # Formatting '/build/vm-state-client/tmp.ltNNUoEYjK', fmt=raw size=1073741824
  62. 1.77 s [vm-test-run-centjes-e2e-test] client # Discarding device blocks: 0/262144 done
  63. 1.77 s [vm-test-run-centjes-e2e-test] docsserver: waiting for unit default.target
  64. 1.77 s [vm-test-run-centjes-e2e-test] client # Creating filesystem with 262144 4k blocks and 65536 inodes
  65. 1.77 s [vm-test-run-centjes-e2e-test] docsserver: waiting for the VM to finish booting
  66. 1.77 s [vm-test-run-centjes-e2e-test] client # Filesystem UUID: 656f52a2-c5ec-4f87-b164-450992204d1a
  67. 1.77 s [vm-test-run-centjes-e2e-test] client # Superblock backups stored on blocks:
  68. 1.77 s [vm-test-run-centjes-e2e-test] client # 32768, 98304, 163840, 229376
  69. 1.77 s [vm-test-run-centjes-e2e-test] client #
  70. 1.77 s [vm-test-run-centjes-e2e-test] client # Allocating group tables: 0/8 done
  71. 1.77 s [vm-test-run-centjes-e2e-test] client # Writing inode tables: 0/8 done
  72. 1.77 s [vm-test-run-centjes-e2e-test] client # Creating journal (8192 blocks): done
  73. 1.77 s [vm-test-run-centjes-e2e-test] client # Writing superblocks and filesystem accounting information: 0/8 done
  74. 1.77 s [vm-test-run-centjes-e2e-test] client #
  75. 1.77 s [vm-test-run-centjes-e2e-test] client # Virtualisation disk image created.
  76. 1.84 s [vm-test-run-centjes-e2e-test] docsserver #
  77. 1.84 s [vm-test-run-centjes-e2e-test] docsserver #
  78. 1.84 s [vm-test-run-centjes-e2e-test] docsserver # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+3EFD1920+3EF31920 CA00
  79. 1.86 s [vm-test-run-centjes-e2e-test] docsserver # Press Ctrl-B to configure iPXE (PCI 00:03.0)...
  80. 1.86 s [vm-test-run-centjes-e2e-test] docsserver #
  81. 1.86 s [vm-test-run-centjes-e2e-test] docsserver #
  82. 1.86 s [vm-test-run-centjes-e2e-test] docsserver #
  83. 1.86 s [vm-test-run-centjes-e2e-test] docsserver #
  84. 1.86 s [vm-test-run-centjes-e2e-test] docsserver # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM 3EFD1920 3EF31920 CB00
  85. 1.88 s [vm-test-run-centjes-e2e-test] docsserver # Press Ctrl-B to configure iPXE (PCI 00:09.0)...
  86. 1.88 s [vm-test-run-centjes-e2e-test] docsserver #
  87. 1.88 s [vm-test-run-centjes-e2e-test] docsserver #
  88. 1.89 s [vm-test-run-centjes-e2e-test] docsserver # Booting from ROM...
  89. 1.90 s [vm-test-run-centjes-e2e-test] docsserver # Probing EDD (edd=off to disable)... ok
  90. 1.92 s [vm-test-run-centjes-e2e-test] client # c[?7lSeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)
  91. 1.97 s [vm-test-run-centjes-e2e-test] client #
  92. 1.97 s [vm-test-run-centjes-e2e-test] client #
  93. 1.98 s [vm-test-run-centjes-e2e-test] client # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+3EFD1920+3EF31920 CA00
  94. 1.99 s [vm-test-run-centjes-e2e-test] client # Press Ctrl-B to configure iPXE (PCI 00:03.0)...
  95. 1.99 s [vm-test-run-centjes-e2e-test] client #
  96. 1.99 s [vm-test-run-centjes-e2e-test] client #
  97. 1.99 s [vm-test-run-centjes-e2e-test] client #
  98. 1.99 s [vm-test-run-centjes-e2e-test] client #
  99. 2.00 s [vm-test-run-centjes-e2e-test] client # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM 3EFD1920 3EF31920 CB00
  100. 2.02 s [vm-test-run-centjes-e2e-test] client # Press Ctrl-B to configure iPXE (PCI 00:09.0)...
  101. 2.02 s [vm-test-run-centjes-e2e-test] client #
  102. 2.02 s [vm-test-run-centjes-e2e-test] client #
  103. 2.03 s [vm-test-run-centjes-e2e-test] client # Booting from ROM...
  104. 2.21 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
  105. 2.22 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/iq1h1hr53bq6xw39wn2hg1rbi77vrj15-nixos-system-docsserver-test/init regInfo=/nix/store/vprsiwswk3h4v1zxy6ghbxcbj597pnml-closure-info/registration console=ttyS0,115200n8 console=tty0
  106. 2.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-provided physical RAM map:
  107. 2.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
  108. 2.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
  109. 2.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
  110. 2.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffdafff] usable
  111. 2.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x000000003ffdb000-0x000000003fffffff] reserved
  112. 2.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
  113. 2.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
  114. 2.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved
  115. 2.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] NX (Execute Disable) protection: active
  116. 2.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] APIC: Static calls initialized
  117. 2.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] SMBIOS 2.8 present.
  118. 2.25 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
  119. 2.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] DMI: Memory slots populated: 1/1
  120. 2.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] Hypervisor detected: KVM
  121. 2.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000
  122. 2.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
  123. 2.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000000] kvm-clock: using sched offset of 427922388 cycles
  124. 2.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000001] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
  125. 2.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000004] tsc: Detected 3399.996 MHz processor
  126. 2.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000712] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000
  127. 2.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000925] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs
  128. 2.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.000933] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT
  129. 2.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002773] found SMP MP-table at [mem 0x000f5470-0x000f547f]
  130. 2.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002785] Using GB pages for direct mapping
  131. 2.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002860] RAMDISK: [mem 0x3f3cd000-0x3ffcffff]
  132. 2.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002880] ACPI: Early table checksum verification disabled
  133. 2.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002883] ACPI: RSDP 0x00000000000F5290 000014 (v00 BOCHS )
  134. 2.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002886] ACPI: RSDT 0x000000003FFE23D9 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  135. 2.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002889] ACPI: FACP 0x000000003FFE228D 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  136. 2.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002892] ACPI: DSDT 0x000000003FFE0040 00224D (v01 BOCHS BXPC 00000001 BXPC 00000001)
  137. 2.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002894] ACPI: FACS 0x000000003FFE0000 000040
  138. 2.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002896] ACPI: APIC 0x000000003FFE2301 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)
  139. 2.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002897] ACPI: HPET 0x000000003FFE2379 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  140. 2.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002898] ACPI: WAET 0x000000003FFE23B1 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  141. 2.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002900] ACPI: Reserving FACP table memory at [mem 0x3ffe228d-0x3ffe2300]
  142. 2.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002901] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe228c]
  143. 2.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002901] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]
  144. 2.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002902] ACPI: Reserving APIC table memory at [mem 0x3ffe2301-0x3ffe2378]
  145. 2.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002902] ACPI: Reserving HPET table memory at [mem 0x3ffe2379-0x3ffe23b0]
  146. 2.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.002903] ACPI: Reserving WAET table memory at [mem 0x3ffe23b1-0x3ffe23d8]
  147. 2.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003711] No NUMA configuration found
  148. 2.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003712] Faking a node at [mem 0x0000000000000000-0x000000003ffdafff]
  149. 2.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003715] NODE_DATA(0) allocated [mem 0x3ffd58c0-0x3ffdadff]
  150. 2.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003730] Zone ranges:
  151. 2.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003730] DMA [mem 0x0000000000001000-0x0000000000ffffff]
  152. 2.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003731] DMA32 [mem 0x0000000001000000-0x000000003ffdafff]
  153. 2.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003732] Normal empty
  154. 2.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003733] Device empty
  155. 2.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003734] Movable zone start for each node
  156. 2.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003734] Early memory node ranges
  157. 2.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003734] node 0: [mem 0x0000000000001000-0x000000000009efff]
  158. 2.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003735] node 0: [mem 0x0000000000100000-0x000000003ffdafff]
  159. 2.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003736] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdafff]
  160. 2.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003899] On node 0, zone DMA: 1 pages in unavailable ranges
  161. 2.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.003912] On node 0, zone DMA: 97 pages in unavailable ranges
  162. 2.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.005582] On node 0, zone DMA32: 37 pages in unavailable ranges
  163. 2.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007268] ACPI: PM-Timer IO Port: 0x608
  164. 2.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007295] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
  165. 2.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007386] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
  166. 2.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007388] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
  167. 2.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007390] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
  168. 2.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007391] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
  169. 2.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007391] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
  170. 2.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007392] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
  171. 2.36 s [vm-test-run-centjes-e2e-test] client # Probing EDD (edd=off to disable)... oc[?7lk[ 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
  172. 2.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007394] ACPI: Using ACPI (MADT) for SMP configuration information
  173. 2.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007395] ACPI: HPET id: 0x8086a201 base: 0xfed00000
  174. 2.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007397] TSC deadline timer available
  175. 2.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007401] CPU topo: Max. logical packages: 1
  176. 2.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007401] CPU topo: Max. logical dies: 1
  177. 2.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007402] CPU topo: Max. dies per package: 1
  178. 2.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007405] CPU topo: Max. threads per core: 1
  179. 2.37 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
  180. 2.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007405] CPU topo: Num. cores per package: 1
  181. 2.37 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-provided physical RAM map:
  182. 2.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007406] CPU topo: Num. threads per package: 1
  183. 2.37 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
  184. 2.38 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007406] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs
  185. 2.38 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
  186. 2.38 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007438] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()
  187. 2.38 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
  188. 2.38 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007495] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]
  189. 2.38 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007496] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]
  190. 2.38 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffdafff] usable
  191. 2.38 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007497] [mem 0x40000000-0xfeffbfff] available for PCI devices
  192. 2.39 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x000000003ffdb000-0x000000003fffffff] reserved
  193. 2.39 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007498] Booting paravirtualized kernel on KVM
  194. 2.39 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
  195. 2.39 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.007500] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
  196. 2.39 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
  197. 2.39 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.010945] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1
  198. 2.39 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved
  199. 2.39 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] NX (Execute Disable) protection: active
  200. 2.39 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011202] percpu: Embedded 116 pages/cpu s294912 r65536 d114688 u2097152
  201. 2.40 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] APIC: Static calls initialized
  202. 2.40 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011279] kvm-guest: PV spinlocks disabled, single CPU
  203. 2.40 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] SMBIOS 2.8 present.
  204. 2.40 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
  205. 2.40 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] DMI: Memory slots populated: 1/1
  206. 2.40 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] Hypervisor detected: KVM
  207. 2.41 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000
  208. 2.41 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011280] 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/iq1h1hr53bq6xw39wn2hg1rbi77vrj15-nixos-system-docsserver-test/init regInfo=/nix/store/vprsiwswk3h4v1zxy6ghbxcbj597pnml-closure-info/registration console=ttyS0,115200n8 console=tty0
  209. 2.41 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
  210. 2.41 s [vm-test-run-centjes-e2e-test] client # [ 0.000000] kvm-clock: using sched offset of 440667879 cycles
  211. 2.41 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011354] Unknown kernel command line parameters "regInfo=/nix/store/vprsiwswk3h4v1zxy6ghbxcbj597pnml-closure-info/registration", will be passed to user space.
  212. 2.41 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011368] random: crng init done
  213. 2.41 s [vm-test-run-centjes-e2e-test] client # [ 0.000001] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
  214. 2.42 s [vm-test-run-centjes-e2e-test] client # [ 0.000003] tsc: Detected 3399.996 MHz processor
  215. 2.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011426] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
  216. 2.42 s [vm-test-run-centjes-e2e-test] client # [ 0.000703] last_pfn = 0x3ffdb max_arch_pfn = 0x400000000
  217. 2.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011459] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
  218. 2.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011497] Fallback order for Node 0: 0
  219. 2.42 s [vm-test-run-centjes-e2e-test] client # [ 0.000909] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs
  220. 2.42 s [vm-test-run-centjes-e2e-test] client # [ 0.000916] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT
  221. 2.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011499] Built 1 zonelists, mobility grouping on. Total pages: 262009
  222. 2.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011500] Policy zone: DMA32
  223. 2.42 s [vm-test-run-centjes-e2e-test] client # [ 0.002720] found SMP MP-table at [mem 0x000f5470-0x000f547f]
  224. 2.43 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.011710] mem auto-init: stack:all(zero), heap alloc:on, heap free:off
  225. 2.43 s [vm-test-run-centjes-e2e-test] client # [ 0.002729] Using GB pages for direct mapping
  226. 2.43 s [vm-test-run-centjes-e2e-test] client # [ 0.002857] RAMDISK: [mem 0x3f3cd000-0x3ffcffff]
  227. 2.43 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.013823] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
  228. 2.43 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.014104] allocated 2097152 bytes of page_ext
  229. 2.43 s [vm-test-run-centjes-e2e-test] client # [ 0.002880] ACPI: Early table checksum verification disabled
  230. 2.43 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.014116] ftrace: allocating 46208 entries in 181 pages
  231. 2.43 s [vm-test-run-centjes-e2e-test] client # [ 0.002883] ACPI: RSDP 0x00000000000F5290 000014 (v00 BOCHS )
  232. 2.43 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.021760] ftrace: allocated 181 pages with 5 groups
  233. 2.43 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.022354] Dynamic Preempt: voluntary
  234. 2.43 s [vm-test-run-centjes-e2e-test] client # [ 0.002886] ACPI: RSDT 0x000000003FFE23D9 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  235. 2.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.022577] rcu: Preemptible hierarchical RCU implementation.
  236. 2.44 s [vm-test-run-centjes-e2e-test] client # [ 0.002889] ACPI: FACP 0x000000003FFE228D 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  237. 2.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.022577] rcu: RCU event tracing is enabled.
  238. 2.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.022577] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.
  239. 2.44 s [vm-test-run-centjes-e2e-test] client # [ 0.002892] ACPI: DSDT 0x000000003FFE0040 00224D (v01 BOCHS BXPC 00000001 BXPC 00000001)
  240. 2.44 s [vm-test-run-centjes-e2e-test] client # [ 0.002894] ACPI: FACS 0x000000003FFE0000 000040
  241. 2.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.022579] Trampoline variant of Tasks RCU enabled.
  242. 2.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.022579] Rude variant of Tasks RCU enabled.
  243. 2.44 s [vm-test-run-centjes-e2e-test] client # [ 0.002895] ACPI: APIC 0x000000003FFE2301 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)
  244. 2.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.022579] Tracing variant of Tasks RCU enabled.
  245. 2.45 s [vm-test-run-centjes-e2e-test] client # [ 0.002897] ACPI: HPET 0x000000003FFE2379 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  246. 2.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.022579] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
  247. 2.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.022580] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
  248. 2.45 s [vm-test-run-centjes-e2e-test] client # [ 0.002898] ACPI: WAET 0x000000003FFE23B1 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)
  249. 2.45 s [vm-test-run-centjes-e2e-test] client # [ 0.002899] ACPI: Reserving FACP table memory at [mem 0x3ffe228d-0x3ffe2300]
  250. 2.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.022584] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
  251. 2.46 s [vm-test-run-centjes-e2e-test] client # [ 0.002900] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe228c]
  252. 2.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.022585] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
  253. 2.46 s [vm-test-run-centjes-e2e-test] client # [ 0.002901] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]
  254. 2.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.022586] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
  255. 2.46 s [vm-test-run-centjes-e2e-test] client # [ 0.002901] ACPI: Reserving APIC table memory at [mem 0x3ffe2301-0x3ffe2378]
  256. 2.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.025983] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16
  257. 2.46 s [vm-test-run-centjes-e2e-test] client # [ 0.002902] ACPI: Reserving HPET table memory at [mem 0x3ffe2379-0x3ffe23b0]
  258. 2.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.026244] rcu: srcu_init: Setting srcu_struct sizes based on contention.
  259. 2.47 s [vm-test-run-centjes-e2e-test] client # [ 0.002902] ACPI: Reserving WAET table memory at [mem 0x3ffe23b1-0x3ffe23d8]
  260. 2.47 s [vm-test-run-centjes-e2e-test] client # [ 0.003677] No NUMA configuration found
  261. 2.47 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.026312] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)
  262. 2.47 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.040520] Console: colour VGA+ 80x25
  263. 2.47 s [vm-test-run-centjes-e2e-test] client # [ 0.003677] Faking a node at [mem 0x0000000000000000-0x000000003ffdafff]
  264. 2.47 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.040524] printk: legacy console [tty0] enabled
  265. 2.47 s [vm-test-run-centjes-e2e-test] client # [ 0.003680] NODE_DATA(0) allocated [mem 0x3ffd58c0-0x3ffdadff]
  266. 2.47 s [vm-test-run-centjes-e2e-test] client # [ 0.003693] Zone ranges:
  267. 2.47 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.148647] printk: legacy console [ttyS0] enabled
  268. 2.47 s [vm-test-run-centjes-e2e-test] client # [ 0.003694] DMA [mem 0x0000000000001000-0x0000000000ffffff]
  269. 2.47 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.412208] ACPI: Core revision 20240827
  270. 2.48 s [vm-test-run-centjes-e2e-test] client # [ 0.003695] DMA32 [mem 0x0000000001000000-0x000000003ffdafff]
  271. 2.48 s [vm-test-run-centjes-e2e-test] client # [ 0.003695] Normal empty
  272. 2.48 s [vm-test-run-centjes-e2e-test] client # [ 0.003696] Device empty
  273. 2.48 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.414643] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
  274. 2.48 s [vm-test-run-centjes-e2e-test] client # [ 0.003696] Movable zone start for each node
  275. 2.48 s [vm-test-run-centjes-e2e-test] client # [ 0.003697] Early memory node ranges
  276. 2.48 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.419442] APIC: Switch to symmetric I/O mode setup
  277. 2.48 s [vm-test-run-centjes-e2e-test] client # [ 0.003698] node 0: [mem 0x0000000000001000-0x000000000009efff]
  278. 2.48 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.422995] x2apic enabled
  279. 2.49 s [vm-test-run-centjes-e2e-test] client # [ 0.003698] node 0: [mem 0x0000000000100000-0x000000003ffdafff]
  280. 2.49 s [vm-test-run-centjes-e2e-test] client # [ 0.003699] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdafff]
  281. 2.49 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.425475] APIC: Switched APIC routing to: physical x2apic
  282. 2.49 s [vm-test-run-centjes-e2e-test] client # [ 0.003855] On node 0, zone DMA: 1 pages in unavailable ranges
  283. 2.49 s [vm-test-run-centjes-e2e-test] client # [ 0.003867] On node 0, zone DMA: 97 pages in unavailable ranges
  284. 2.49 s [vm-test-run-centjes-e2e-test] client # [ 0.005554] On node 0, zone DMA32: 37 pages in unavailable ranges
  285. 2.50 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.432005] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
  286. 2.50 s [vm-test-run-centjes-e2e-test] client # [ 0.007189] ACPI: PM-Timer IO Port: 0x608
  287. 2.50 s [vm-test-run-centjes-e2e-test] client # [ 0.007216] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
  288. 2.50 s [vm-test-run-centjes-e2e-test] client # [ 0.007305] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
  289. 2.50 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.434972] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns
  290. 2.50 s [vm-test-run-centjes-e2e-test] client # [ 0.007307] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
  291. 2.50 s [vm-test-run-centjes-e2e-test] client # [ 0.007308] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
  292. 2.50 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.439906] Calibrating delay loop (skipped) preset value.. 6799.99 BogoMIPS (lpj=3399996)
  293. 2.51 s [vm-test-run-centjes-e2e-test] client # [ 0.007309] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
  294. 2.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.443016] x86/cpu: User Mode Instruction Prevention (UMIP) activated
  295. 2.51 s [vm-test-run-centjes-e2e-test] client # [ 0.007309] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
  296. 2.51 s [vm-test-run-centjes-e2e-test] client # [ 0.007310] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
  297. 2.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.445378] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127
  298. 2.51 s [vm-test-run-centjes-e2e-test] client # [ 0.007312] ACPI: Using ACPI (MADT) for SMP configuration information
  299. 2.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.446905] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0
  300. 2.52 s [vm-test-run-centjes-e2e-test] client # [ 0.007313] ACPI: HPET id: 0x8086a201 base: 0xfed00000
  301. 2.52 s [vm-test-run-centjes-e2e-test] client # [ 0.007316] TSC deadline timer available
  302. 2.52 s [vm-test-run-centjes-e2e-test] client # [ 0.007320] CPU topo: Max. logical packages: 1
  303. 2.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.448915] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
  304. 2.52 s [vm-test-run-centjes-e2e-test] client # [ 0.007320] CPU topo: Max. logical dies: 1
  305. 2.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.450905] Spectre V2 : Mitigation: Retpolines
  306. 2.52 s [vm-test-run-centjes-e2e-test] client # [ 0.007321] CPU topo: Max. dies per package: 1
  307. 2.52 s [vm-test-run-centjes-e2e-test] client # [ 0.007324] CPU topo: Max. threads per core: 1
  308. 2.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.452904] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT
  309. 2.52 s [vm-test-run-centjes-e2e-test] client # [ 0.007324] CPU topo: Num. cores per package: 1
  310. 2.53 s [vm-test-run-centjes-e2e-test] client # [ 0.007325] CPU topo: Num. threads per package: 1
  311. 2.53 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.454904] Spectre V2 : Enabling Restricted Speculation for firmware calls
  312. 2.53 s [vm-test-run-centjes-e2e-test] client # [ 0.007325] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs
  313. 2.53 s [vm-test-run-centjes-e2e-test] client # [ 0.007355] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()
  314. 2.53 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.456906] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
  315. 2.53 s [vm-test-run-centjes-e2e-test] client # [ 0.007411] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]
  316. 2.54 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.458905] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl
  317. 2.54 s [vm-test-run-centjes-e2e-test] client # [ 0.007412] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]
  318. 2.54 s [vm-test-run-centjes-e2e-test] client # [ 0.007414] [mem 0x40000000-0xfeffbfff] available for PCI devices
  319. 2.54 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.460905] active return thunk: srso_alias_return_thunk
  320. 2.54 s [vm-test-run-centjes-e2e-test] client # [ 0.007414] Booting paravirtualized kernel on KVM
  321. 2.54 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.461905] Speculative Return Stack Overflow: Mitigation: Safe RET
  322. 2.54 s [vm-test-run-centjes-e2e-test] client # [ 0.007416] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
  323. 2.54 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.463904] Transient Scheduler Attacks: Forcing mitigation on in a VM
  324. 2.55 s [vm-test-run-centjes-e2e-test] client # [ 0.010862] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1
  325. 2.55 s [vm-test-run-centjes-e2e-test] client # [ 0.011155] percpu: Embedded 116 pages/cpu s294912 r65536 d114688 u2097152
  326. 2.55 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.465904] Transient Scheduler Attacks: Vulnerable: Clear CPU buffers attempted, no microcode
  327. 2.55 s [vm-test-run-centjes-e2e-test] client # [ 0.011229] kvm-guest: PV spinlocks disabled, single CPU
  328. 2.55 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.467990] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
  329. 2.56 s [vm-test-run-centjes-e2e-test] client # [ 0.011230] 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
  330. 2.57 s [vm-test-run-centjes-e2e-test] client # [ 0.011300] Unknown kernel command line parameters "regInfo=/nix/store/j7kn31fka8vp5bhm6sw8hv64iyzix7xw-closure-info/registration", will be passed to user space.
  331. 2.57 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.469905] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
  332. 2.57 s [vm-test-run-centjes-e2e-test] client # [ 0.011314] random: crng init done
  333. 2.57 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.471904] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
  334. 2.57 s [vm-test-run-centjes-e2e-test] client # [ 0.011369] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
  335. 2.57 s [vm-test-run-centjes-e2e-test] client # [ 0.011401] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
  336. 2.57 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.473905] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'
  337. 2.57 s [vm-test-run-centjes-e2e-test] client # [ 0.011436] Fallback order for Node 0: 0
  338. 2.58 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.475905] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256
  339. 2.58 s [vm-test-run-centjes-e2e-test] client # [ 0.011438] Built 1 zonelists, mobility grouping on. Total pages: 262009
  340. 2.58 s [vm-test-run-centjes-e2e-test] client # [ 0.011439] Policy zone: DMA32
  341. 2.58 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.477905] x86/fpu: xstate_offset[9]: 832, xstate_sizes[9]: 8
  342. 2.58 s [vm-test-run-centjes-e2e-test] client # [ 0.011649] mem auto-init: stack:all(zero), heap alloc:on, heap free:off
  343. 2.58 s [vm-test-run-centjes-e2e-test] client # [ 0.013761] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
  344. 2.58 s [vm-test-run-centjes-e2e-test] client # [ 0.014003] allocated 2097152 bytes of page_ext
  345. 2.58 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.479905] x86/fpu: Enabled xstate features 0x207, context size is 840 bytes, using 'compacted' format.
  346. 2.58 s [vm-test-run-centjes-e2e-test] client # [ 0.014019] ftrace: allocating 46208 entries in 181 pages
  347. 2.59 s [vm-test-run-centjes-e2e-test] client # [ 0.021669] ftrace: allocated 181 pages with 5 groups
  348. 2.59 s [vm-test-run-centjes-e2e-test] client # [ 0.022271] Dynamic Preempt: voluntary
  349. 2.59 s [vm-test-run-centjes-e2e-test] client # [ 0.022474] rcu: Preemptible hierarchical RCU implementation.
  350. 2.59 s [vm-test-run-centjes-e2e-test] client # [ 0.022474] rcu: RCU event tracing is enabled.
  351. 2.59 s [vm-test-run-centjes-e2e-test] client # [ 0.022475] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.
  352. 2.60 s [vm-test-run-centjes-e2e-test] client # [ 0.022476] Trampoline variant of Tasks RCU enabled.
  353. 2.60 s [vm-test-run-centjes-e2e-test] client # [ 0.022476] Rude variant of Tasks RCU enabled.
  354. 2.60 s [vm-test-run-centjes-e2e-test] client # [ 0.022477] Tracing variant of Tasks RCU enabled.
  355. 2.60 s [vm-test-run-centjes-e2e-test] client # [ 0.022477] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
  356. 2.60 s [vm-test-run-centjes-e2e-test] client # [ 0.022478] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
  357. 2.61 s [vm-test-run-centjes-e2e-test] client # [ 0.022482] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
  358. 2.61 s [vm-test-run-centjes-e2e-test] client # [ 0.022483] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
  359. 2.61 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.507238] Freeing SMP alternatives memory: 40K
  360. 2.61 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.508906] pid_max: default: 32768 minimum: 301
  361. 2.61 s [vm-test-run-centjes-e2e-test] client # [ 0.022484] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
  362. 2.62 s [vm-test-run-centjes-e2e-test] client # [ 0.026012] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16
  363. 2.62 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.510949] LSM: initializing lsm=capability,landlock,yama,bpf
  364. 2.62 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.512982] landlock: Up and running.
  365. 2.62 s [vm-test-run-centjes-e2e-test] client # [ 0.026302] rcu: srcu_init: Setting srcu_struct sizes based on contention.
  366. 2.62 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.513910] Yama: becoming mindful.
  367. 2.62 s [vm-test-run-centjes-e2e-test] client # [ 0.026378] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)
  368. 2.62 s [vm-test-run-centjes-e2e-test] client # [ 0.040686] Console: colour VGA+ 80x25
  369. 2.62 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.516132] LSM support for eBPF active
  370. 2.62 s [vm-test-run-centjes-e2e-test] client # [ 0.040689] printk: legacy console [tty0] enabled
  371. 2.63 s [vm-test-run-centjes-e2e-test] client # [ 0.150081] printk: legacy console [ttyS0] enabled
  372. 2.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.517931] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
  373. 2.63 s [vm-test-run-centjes-e2e-test] client # [ 0.421051] ACPI: Core revision 20240827
  374. 2.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.519906] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
  375. 2.63 s [vm-test-run-centjes-e2e-test] client # [ 0.423440] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
  376. 2.63 s [vm-test-run-centjes-e2e-test] client # [ 0.427939] APIC: Switch to symmetric I/O mode setup
  377. 2.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.522342] smpboot: CPU0: AMD Ryzen 9 5950X 16-Core Processor (family: 0x19, model: 0x21, stepping: 0x0)
  378. 2.64 s [vm-test-run-centjes-e2e-test] client # [ 0.431260] x2apic enabled
  379. 2.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.524172] Performance Events: Fam17h+ core perfctr, AMD PMU driver.
  380. 2.64 s [vm-test-run-centjes-e2e-test] client # [ 0.433646] APIC: Switched APIC routing to: physical x2apic
  381. 2.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.525921] ... version: 0
  382. 2.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.526913] ... bit width: 48
  383. 2.65 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.527913] ... generic registers: 6
  384. 2.65 s [vm-test-run-centjes-e2e-test] client # [ 0.440162] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
  385. 2.65 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.528913] ... value mask: 0000ffffffffffff
  386. 2.65 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.529913] ... max period: 00007fffffffffff
  387. 2.65 s [vm-test-run-centjes-e2e-test] client # [ 0.443072] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns
  388. 2.65 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.530913] ... fixed-purpose events: 0
  389. 2.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.531913] ... event mask: 000000000000003f
  390. 2.66 s [vm-test-run-centjes-e2e-test] client # [ 0.447958] Calibrating delay loop (skipped) preset value.. 6799.99 BogoMIPS (lpj=3399996)
  391. 2.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.532992] signal: max sigframe size: 3376
  392. 2.66 s [vm-test-run-centjes-e2e-test] client # [ 0.451510] x86/cpu: User Mode Instruction Prevention (UMIP) activated
  393. 2.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.533949] rcu: Hierarchical SRCU implementation.
  394. 2.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.534914] rcu: Max phase no-delay instances is 400.
  395. 2.66 s [vm-test-run-centjes-e2e-test] client # [ 0.454089] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127
  396. 2.67 s [vm-test-run-centjes-e2e-test] client # [ 0.454957] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0
  397. 2.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.539422] smp: Bringing up secondary CPUs ...
  398. 2.67 s [vm-test-run-centjes-e2e-test] client # [ 0.456967] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
  399. 2.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.540931] smp: Brought up 1 node, 1 CPU
  400. 2.67 s [vm-test-run-centjes-e2e-test] client # [ 0.458958] Spectre V2 : Mitigation: Retpolines
  401. 2.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.541917] smpboot: Total of 1 processors activated (6799.99 BogoMIPS)
  402. 2.68 s [vm-test-run-centjes-e2e-test] client # [ 0.459957] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT
  403. 2.68 s [vm-test-run-centjes-e2e-test] client # [ 0.461957] Spectre V2 : Enabling Restricted Speculation for firmware calls
  404. 2.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.544271] Memory: 962148K/1048036K available (16384K kernel code, 2645K rwdata, 12580K rodata, 3408K init, 3360K bss, 78836K reserved, 0K cma-reserved)
  405. 2.68 s [vm-test-run-centjes-e2e-test] client # [ 0.463959] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
  406. 2.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.546024] devtmpfs: initialized
  407. 2.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.546970] x86/mm: Memory block size: 128MB
  408. 2.69 s [vm-test-run-centjes-e2e-test] client # [ 0.465958] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl
  409. 2.69 s [vm-test-run-centjes-e2e-test] client # [ 0.467957] active return thunk: srso_alias_return_thunk
  410. 2.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.548398] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
  411. 2.69 s [vm-test-run-centjes-e2e-test] client # [ 0.469957] Speculative Return Stack Overflow: Mitigation: Safe RET
  412. 2.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.549917] futex hash table entries: 256 (order: 2, 16384 bytes, linear)
  413. 2.70 s [vm-test-run-centjes-e2e-test] client # [ 0.471957] Transient Scheduler Attacks: Forcing mitigation on in a VM
  414. 2.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.550965] pinctrl core: initialized pinctrl subsystem
  415. 2.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.552221] PM: RTC time: 17:53:43, date: 2026-04-27
  416. 2.70 s [vm-test-run-centjes-e2e-test] client # [ 0.473957] Transient Scheduler Attacks: Vulnerable: Clear CPU buffers attempted, no microcode
  417. 2.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.554541] NET: Registered PF_NETLINK/PF_ROUTE protocol family
  418. 2.70 s [vm-test-run-centjes-e2e-test] client # [ 0.476042] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
  419. 2.71 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.556087] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations
  420. 2.71 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.557923] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
  421. 2.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.559922] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
  422. 2.72 s [vm-test-run-centjes-e2e-test] client # [ 0.477957] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
  423. 2.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.561939] audit: initializing netlink subsys (disabled)
  424. 2.72 s [vm-test-run-centjes-e2e-test] client # [ 0.479957] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
  425. 2.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.563975] audit: type=2000 audit(1777312423.175:1): state=initialized audit_enabled=0 res=1
  426. 2.72 s [vm-test-run-centjes-e2e-test] client # [ 0.481964] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'
  427. 2.73 s [vm-test-run-centjes-e2e-test] client # [ 0.483958] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256
  428. 2.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.566083] thermal_sys: Registered thermal governor 'bang_bang'
  429. 2.73 s [vm-test-run-centjes-e2e-test] client # [ 0.485957] x86/fpu: xstate_offset[9]: 832, xstate_sizes[9]: 8
  430. 2.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.566085] thermal_sys: Registered thermal governor 'step_wise'
  431. 2.74 s [vm-test-run-centjes-e2e-test] client # [ 0.487964] x86/fpu: Enabled xstate features 0x207, context size is 840 bytes, using 'compacted' format.
  432. 2.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.567921] thermal_sys: Registered thermal governor 'user_space'
  433. 2.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.569950] cpuidle: using governor menu
  434. 2.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.574835] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
  435. 2.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.576181] PCI: Using configuration type 1 for base access
  436. 2.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.577912] PCI: Using configuration type 1 for extended access
  437. 2.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.579054] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
  438. 2.76 s [vm-test-run-centjes-e2e-test] client # [ 0.516018] Freeing SMP alternatives memory: 40K
  439. 2.77 s [vm-test-run-centjes-e2e-test] client # [ 0.516959] pid_max: default: 32768 minimum: 301
  440. 2.77 s [vm-test-run-centjes-e2e-test] client # [ 0.519008] LSM: initializing lsm=capability,landlock,yama,bpf
  441. 2.77 s [vm-test-run-centjes-e2e-test] client # [ 0.521032] landlock: Up and running.
  442. 2.77 s [vm-test-run-centjes-e2e-test] client # [ 0.522957] Yama: becoming mindful.
  443. 2.78 s [vm-test-run-centjes-e2e-test] client # [ 0.525164] LSM support for eBPF active
  444. 2.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.600118] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
  445. 2.78 s [vm-test-run-centjes-e2e-test] client # [ 0.526980] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
  446. 2.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.601912] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page
  447. 2.78 s [vm-test-run-centjes-e2e-test] client # [ 0.528959] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
  448. 2.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.602913] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
  449. 2.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.603913] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
  450. 2.79 s [vm-test-run-centjes-e2e-test] client # [ 0.531370] smpboot: CPU0: AMD Ryzen 9 5950X 16-Core Processor (family: 0x19, model: 0x21, stepping: 0x0)
  451. 2.79 s [vm-test-run-centjes-e2e-test] client # [ 0.533349] Performance Events: Fam17h+ core perfctr, AMD PMU driver.
  452. 2.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.609838] ACPI: Added _OSI(Module Device)
  453. 2.79 s [vm-test-run-centjes-e2e-test] client # [ 0.534973] ... version: 0
  454. 2.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.610913] ACPI: Added _OSI(Processor Device)
  455. 2.80 s [vm-test-run-centjes-e2e-test] client # [ 0.535966] ... bit width: 48
  456. 2.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.611913] ACPI: Added _OSI(Processor Aggregator Device)
  457. 2.80 s [vm-test-run-centjes-e2e-test] client # [ 0.536966] ... generic registers: 6
  458. 2.80 s [vm-test-run-centjes-e2e-test] client # [ 0.537966] ... value mask: 0000ffffffffffff
  459. 2.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.614491] ACPI: 1 ACPI AML tables successfully acquired and loaded
  460. 2.80 s [vm-test-run-centjes-e2e-test] client # [ 0.538966] ... max period: 00007fffffffffff
  461. 2.81 s [vm-test-run-centjes-e2e-test] client # [ 0.539966] ... fixed-purpose events: 0
  462. 2.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.617331] ACPI: Interpreter enabled
  463. 2.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.618926] ACPI: PM: (supports S0 S3 S4 S5)
  464. 2.81 s [vm-test-run-centjes-e2e-test] client # [ 0.540966] ... event mask: 000000000000003f
  465. 2.81 s [vm-test-run-centjes-e2e-test] client # [ 0.542073] signal: max sigframe size: 3376
  466. 2.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.619913] ACPI: Using IOAPIC for interrupt routing
  467. 2.81 s [vm-test-run-centjes-e2e-test] client # [ 0.543018] rcu: Hierarchical SRCU implementation.
  468. 2.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.620929] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
  469. 2.82 s [vm-test-run-centjes-e2e-test] client # [ 0.543966] rcu: Max phase no-delay instances is 400.
  470. 2.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.622913] PCI: Using E820 reservations for host bridge windows
  471. 2.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.624063] ACPI: Enabled 2 GPEs in block 00 to 0F
  472. 2.82 s [vm-test-run-centjes-e2e-test] client # [ 0.548137] smp: Bringing up secondary CPUs ...
  473. 2.82 s [vm-test-run-centjes-e2e-test] client # [ 0.548980] smp: Brought up 1 node, 1 CPU
  474. 2.83 s [vm-test-run-centjes-e2e-test] client # [ 0.549968] smpboot: Total of 1 processors activated (6799.99 BogoMIPS)
  475. 2.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.628681] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
  476. 2.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.629917] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]
  477. 2.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.632265] acpiphp: Slot [3] registered
  478. 2.83 s [vm-test-run-centjes-e2e-test] client # [ 0.551515] Memory: 962156K/1048036K available (16384K kernel code, 2645K rwdata, 12580K rodata, 3408K init, 3360K bss, 78836K reserved, 0K cma-reserved)
  479. 2.84 s [vm-test-run-centjes-e2e-test] client # [ 0.553079] devtmpfs: initialized
  480. 2.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.633952] acpiphp: Slot [4] registered
  481. 2.84 s [vm-test-run-centjes-e2e-test] client # [ 0.554037] x86/mm: Memory block size: 128MB
  482. 2.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.634956] acpiphp: Slot [5] registered
  483. 2.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.635953] acpiphp: Slot [6] registered
  484. 2.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.636954] acpiphp: Slot [7] registered
  485. 2.84 s [vm-test-run-centjes-e2e-test] client # [ 0.555434] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
  486. 2.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.637968] acpiphp: Slot [8] registered
  487. 2.85 s [vm-test-run-centjes-e2e-test] client # [ 0.556968] futex hash table entries: 256 (order: 2, 16384 bytes, linear)
  488. 2.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.638966] acpiphp: Slot [9] registered
  489. 2.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.639961] acpiphp: Slot [10] registered
  490. 2.85 s [vm-test-run-centjes-e2e-test] client # [ 0.558028] pinctrl core: initialized pinctrl subsystem
  491. 2.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.640985] acpiphp: Slot [11] registered
  492. 2.85 s [vm-test-run-centjes-e2e-test] client # [ 0.559270] PM: RTC time: 17:53:43, date: 2026-04-27
  493. 2.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.641971] acpiphp: Slot [12] registered
  494. 2.86 s [vm-test-run-centjes-e2e-test] client # [ 0.561724] NET: Registered PF_NETLINK/PF_ROUTE protocol family
  495. 2.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.642960] acpiphp: Slot [13] registered
  496. 2.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.643970] acpiphp: Slot [14] registered
  497. 2.86 s [vm-test-run-centjes-e2e-test] client # [ 0.563120] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations
  498. 2.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.644953] acpiphp: Slot [15] registered
  499. 2.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.645954] acpiphp: Slot [16] registered
  500. 2.86 s [vm-test-run-centjes-e2e-test] client # [ 0.564976] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
  501. 2.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.646952] acpiphp: Slot [17] registered
  502. 2.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.647952] acpiphp: Slot [18] registered
  503. 2.87 s [vm-test-run-centjes-e2e-test] client # [ 0.566974] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
  504. 2.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.648953] acpiphp: Slot [19] registered
  505. 2.87 s [vm-test-run-centjes-e2e-test] client # [ 0.568976] audit: initializing netlink subsys (disabled)
  506. 2.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.649954] acpiphp: Slot [20] registered
  507. 2.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.650952] acpiphp: Slot [21] registered
  508. 2.87 s [vm-test-run-centjes-e2e-test] client # [ 0.570022] audit: type=2000 audit(1777312423.318:1): state=initialized audit_enabled=0 res=1
  509. 2.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.651951] acpiphp: Slot [22] registered
  510. 2.88 s [vm-test-run-centjes-e2e-test] client # [ 0.572083] thermal_sys: Registered thermal governor 'bang_bang'
  511. 2.88 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.652952] acpiphp: Slot [23] registered
  512. 2.88 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.653954] acpiphp: Slot [24] registered
  513. 2.88 s [vm-test-run-centjes-e2e-test] client # [ 0.572085] thermal_sys: Registered thermal governor 'step_wise'
  514. 2.88 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.654952] acpiphp: Slot [25] registered
  515. 2.88 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.655951] acpiphp: Slot [26] registered
  516. 2.88 s [vm-test-run-centjes-e2e-test] client # [ 0.573973] thermal_sys: Registered thermal governor 'user_space'
  517. 2.89 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.656952] acpiphp: Slot [27] registered
  518. 2.89 s [vm-test-run-centjes-e2e-test] client # [ 0.575985] cpuidle: using governor menu
  519. 2.89 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.657955] acpiphp: Slot [28] registered
  520. 2.89 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.658953] acpiphp: Slot [29] registered
  521. 2.89 s [vm-test-run-centjes-e2e-test] client # [ 0.580799] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
  522. 2.89 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.659996] acpiphp: Slot [30] registered
  523. 2.89 s [vm-test-run-centjes-e2e-test] client # [ 0.582203] PCI: Using configuration type 1 for base access
  524. 2.89 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.660959] acpiphp: Slot [31] registered
  525. 2.90 s [vm-test-run-centjes-e2e-test] client # [ 0.583966] PCI: Using configuration type 1 for extended access
  526. 2.90 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.661945] PCI host bridge to bus 0000:00
  527. 2.90 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.662919] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
  528. 2.90 s [vm-test-run-centjes-e2e-test] client # [ 0.585103] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
  529. 2.90 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.663914] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
  530. 2.91 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.664914] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
  531. 2.91 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.666914] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
  532. 2.91 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.668914] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
  533. 2.92 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.670914] pci_bus 0000:00: root bus resource [bus 00-ff]
  534. 2.92 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.672469] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint
  535. 2.92 s [vm-test-run-centjes-e2e-test] client # [ 0.606998] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
  536. 2.93 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.675107] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint
  537. 2.93 s [vm-test-run-centjes-e2e-test] client # [ 0.607966] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page
  538. 2.93 s [vm-test-run-centjes-e2e-test] client # [ 0.608966] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
  539. 2.93 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.678594] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint
  540. 2.93 s [vm-test-run-centjes-e2e-test] client # [ 0.609967] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
  541. 2.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.683775] pci 0000:00:01.1: BAR 4 [io 0xc1e0-0xc1ef]
  542. 2.94 s [vm-test-run-centjes-e2e-test] client # [ 0.615452] ACPI: Added _OSI(Module Device)
  543. 2.94 s [vm-test-run-centjes-e2e-test] client # [ 0.617096] ACPI: Added _OSI(Processor Device)
  544. 2.95 s [vm-test-run-centjes-e2e-test] client # [ 0.617966] ACPI: Added _OSI(Processor Aggregator Device)
  545. 2.95 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.686432] pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk
  546. 2.95 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.687914] pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk
  547. 2.95 s [vm-test-run-centjes-e2e-test] client # [ 0.620583] ACPI: 1 ACPI AML tables successfully acquired and loaded
  548. 2.95 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.688913] pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk
  549. 2.95 s [vm-test-run-centjes-e2e-test] client # [ 0.623181] ACPI: Interpreter enabled
  550. 2.95 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.689913] pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk
  551. 2.96 s [vm-test-run-centjes-e2e-test] client # [ 0.623979] ACPI: PM: (supports S0 S3 S4 S5)
  552. 2.96 s [vm-test-run-centjes-e2e-test] client # [ 0.624966] ACPI: Using IOAPIC for interrupt routing
  553. 2.96 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.691348] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint
  554. 2.96 s [vm-test-run-centjes-e2e-test] client # [ 0.625983] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
  555. 2.96 s [vm-test-run-centjes-e2e-test] client # [ 0.627966] PCI: Using E820 reservations for host bridge windows
  556. 2.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.696553] pci 0000:00:01.2: BAR 4 [io 0xc100-0xc11f]
  557. 2.97 s [vm-test-run-centjes-e2e-test] client # [ 0.629129] ACPI: Enabled 2 GPEs in block 00 to 0F
  558. 2.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.700068] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint
  559. 2.97 s [vm-test-run-centjes-e2e-test] client # [ 0.633596] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
  560. 2.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.703213] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI
  561. 2.98 s [vm-test-run-centjes-e2e-test] client # [ 0.634970] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]
  562. 2.98 s [vm-test-run-centjes-e2e-test] client # [ 0.637296] acpiphp: Slot [3] registered
  563. 2.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.704936] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB
  564. 2.98 s [vm-test-run-centjes-e2e-test] client # [ 0.638014] acpiphp: Slot [4] registered
  565. 2.98 s [vm-test-run-centjes-e2e-test] client # [ 0.639016] acpiphp: Slot [5] registered
  566. 2.99 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.706458] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint
  567. 2.99 s [vm-test-run-centjes-e2e-test] client # [ 0.640040] acpiphp: Slot [6] registered
  568. 2.99 s [vm-test-run-centjes-e2e-test] client # [ 0.641003] acpiphp: Slot [7] registered
  569. 2.99 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.709456] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]
  570. 2.99 s [vm-test-run-centjes-e2e-test] client # [ 0.642020] acpiphp: Slot [8] registered
  571. 2.99 s [vm-test-run-centjes-e2e-test] client # [ 0.643004] acpiphp: Slot [9] registered
  572. 2.99 s [vm-test-run-centjes-e2e-test] client # [ 0.644003] acpiphp: Slot [10] registered
  573. 3.00 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.712935] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]
  574. 3.00 s [vm-test-run-centjes-e2e-test] client # [ 0.645003] acpiphp: Slot [11] registered
  575. 3.00 s [vm-test-run-centjes-e2e-test] client # [ 0.646003] acpiphp: Slot [12] registered
  576. 3.00 s [vm-test-run-centjes-e2e-test] client # [ 0.647004] acpiphp: Slot [13] registered
  577. 3.00 s [vm-test-run-centjes-e2e-test] client # [ 0.648005] acpiphp: Slot [14] registered
  578. 3.00 s [vm-test-run-centjes-e2e-test] client # [ 0.649002] acpiphp: Slot [15] registered
  579. 3.01 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.718901] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref]
  580. 3.01 s [vm-test-run-centjes-e2e-test] client # [ 0.650003] acpiphp: Slot [16] registered
  581. 3.01 s [vm-test-run-centjes-e2e-test] client # [ 0.651001] acpiphp: Slot [17] registered
  582. 3.01 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.720585] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
  583. 3.01 s [vm-test-run-centjes-e2e-test] client # [ 0.652001] acpiphp: Slot [18] registered
  584. 3.01 s [vm-test-run-centjes-e2e-test] client # [ 0.653001] acpiphp: Slot [19] registered
  585. 3.01 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.722652] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
  586. 3.01 s [vm-test-run-centjes-e2e-test] client # [ 0.654003] acpiphp: Slot [20] registered
  587. 3.02 s [vm-test-run-centjes-e2e-test] client # [ 0.655002] acpiphp: Slot [21] registered
  588. 3.02 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.724913] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]
  589. 3.02 s [vm-test-run-centjes-e2e-test] client # [ 0.656001] acpiphp: Slot [22] registered
  590. 3.02 s [vm-test-run-centjes-e2e-test] client # [ 0.657001] acpiphp: Slot [23] registered
  591. 3.02 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.726913] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]
  592. 3.02 s [vm-test-run-centjes-e2e-test] client # [ 0.658003] acpiphp: Slot [24] registered
  593. 3.02 s [vm-test-run-centjes-e2e-test] client # [ 0.659001] acpiphp: Slot [25] registered
  594. 3.03 s [vm-test-run-centjes-e2e-test] client # [ 0.660002] acpiphp: Slot [26] registered
  595. 3.03 s [vm-test-run-centjes-e2e-test] client # [ 0.661001] acpiphp: Slot [27] registered
  596. 3.03 s [vm-test-run-centjes-e2e-test] client # [ 0.662003] acpiphp: Slot [28] registered
  597. 3.03 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.731917] pci 0000:00:03.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]
  598. 3.03 s [vm-test-run-centjes-e2e-test] client # [ 0.663002] acpiphp: Slot [29] registered
  599. 3.04 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.734825] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]
  600. 3.04 s [vm-test-run-centjes-e2e-test] client # [ 0.664002] acpiphp: Slot [30] registered
  601. 3.04 s [vm-test-run-centjes-e2e-test] client # [ 0.665003] acpiphp: Slot [31] registered
  602. 3.04 s [vm-test-run-centjes-e2e-test] client # [ 0.665996] PCI host bridge to bus 0000:00
  603. 3.04 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.738600] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
  604. 3.04 s [vm-test-run-centjes-e2e-test] client # [ 0.666971] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
  605. 3.05 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.740913] pci 0000:00:04.0: BAR 0 [io 0xc140-0xc15f]
  606. 3.05 s [vm-test-run-centjes-e2e-test] client # [ 0.667966] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
  607. 3.05 s [vm-test-run-centjes-e2e-test] client # [ 0.668966] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
  608. 3.05 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.742913] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]
  609. 3.05 s [vm-test-run-centjes-e2e-test] client # [ 0.670966] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
  610. 3.06 s [vm-test-run-centjes-e2e-test] client # [ 0.672966] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
  611. 3.06 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.746759] pci 0000:00:04.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]
  612. 3.06 s [vm-test-run-centjes-e2e-test] client # [ 0.674967] pci_bus 0000:00: root bus resource [bus 00-ff]
  613. 3.06 s [vm-test-run-centjes-e2e-test] client # [ 0.676491] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint
  614. 3.07 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.752529] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
  615. 3.07 s [vm-test-run-centjes-e2e-test] client # [ 0.679153] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint
  616. 3.07 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.754913] pci 0000:00:05.0: BAR 0 [io 0xc080-0xc0bf]
  617. 3.07 s [vm-test-run-centjes-e2e-test] client # [ 0.682618] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint
  618. 3.07 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.756807] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]
  619. 3.08 s [vm-test-run-centjes-e2e-test] client # [ 0.687966] pci 0000:00:01.1: BAR 4 [io 0xc1e0-0xc1ef]
  620. 3.08 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.760917] pci 0000:00:05.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]
  621. 3.09 s [vm-test-run-centjes-e2e-test] client # [ 0.690499] pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk
  622. 3.09 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.765608] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
  623. 3.09 s [vm-test-run-centjes-e2e-test] client # [ 0.691966] pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk
  624. 3.09 s [vm-test-run-centjes-e2e-test] client # [ 0.692966] pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk
  625. 3.09 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.767916] pci 0000:00:06.0: BAR 0 [io 0xc160-0xc17f]
  626. 3.10 s [vm-test-run-centjes-e2e-test] client # [ 0.693965] pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk
  627. 3.10 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.770791] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]
  628. 3.10 s [vm-test-run-centjes-e2e-test] client # [ 0.695404] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint
  629. 3.10 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.774865] pci 0000:00:06.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]
  630. 3.11 s [vm-test-run-centjes-e2e-test] client # [ 0.699965] pci 0000:00:01.2: BAR 4 [io 0xc100-0xc11f]
  631. 3.11 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.779615] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
  632. 3.11 s [vm-test-run-centjes-e2e-test] client # [ 0.703260] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint
  633. 3.12 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.781913] pci 0000:00:07.0: BAR 0 [io 0xc180-0xc19f]
  634. 3.12 s [vm-test-run-centjes-e2e-test] client # [ 0.706324] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI
  635. 3.12 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.783836] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]
  636. 3.12 s [vm-test-run-centjes-e2e-test] client # [ 0.707991] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB
  637. 3.13 s [vm-test-run-centjes-e2e-test] client # [ 0.710571] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint
  638. 3.13 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.788845] pci 0000:00:07.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]
  639. 3.13 s [vm-test-run-centjes-e2e-test] client # [ 0.713643] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]
  640. 3.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.793552] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint
  641. 3.14 s [vm-test-run-centjes-e2e-test] client # [ 0.716988] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]
  642. 3.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.795916] pci 0000:00:08.0: BAR 0 [io 0xc000-0xc07f]
  643. 3.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.797913] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]
  644. 3.15 s [vm-test-run-centjes-e2e-test] client # [ 0.722917] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref]
  645. 3.15 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.802550] pci 0000:00:08.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]
  646. 3.15 s [vm-test-run-centjes-e2e-test] client # [ 0.724134] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
  647. 3.16 s [vm-test-run-centjes-e2e-test] client # [ 0.726713] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
  648. 3.16 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.807601] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
  649. 3.16 s [vm-test-run-centjes-e2e-test] client # [ 0.729524] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]
  650. 3.16 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.809913] pci 0000:00:09.0: BAR 0 [io 0xc1a0-0xc1bf]
  651. 3.17 s [vm-test-run-centjes-e2e-test] client # [ 0.731965] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]
  652. 3.17 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.811805] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]
  653. 3.17 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.815327] pci 0000:00:09.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]
  654. 3.17 s [vm-test-run-centjes-e2e-test] client # [ 0.736968] pci 0000:00:03.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]
  655. 3.18 s [vm-test-run-centjes-e2e-test] client # [ 0.738965] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]
  656. 3.19 s [vm-test-run-centjes-e2e-test] client # [ 0.742783] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
  657. 3.19 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.817856] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref]
  658. 3.19 s [vm-test-run-centjes-e2e-test] client # [ 0.744965] pci 0000:00:04.0: BAR 0 [io 0xc140-0xc15f]
  659. 3.19 s [vm-test-run-centjes-e2e-test] client # [ 0.746965] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]
  660. 3.20 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.821664] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint
  661. 3.20 s [vm-test-run-centjes-e2e-test] client # [ 0.750631] pci 0000:00:04.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]
  662. 3.20 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.824270] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]
  663. 3.21 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.827913] pci 0000:00:0a.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]
  664. 3.21 s [vm-test-run-centjes-e2e-test] client # [ 0.756724] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
  665. 3.21 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.832812] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint
  666. 3.21 s [vm-test-run-centjes-e2e-test] client # [ 0.758965] pci 0000:00:05.0: BAR 0 [io 0xc080-0xc0bf]
  667. 3.22 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.834913] pci 0000:00:0b.0: BAR 0 [io 0xc0c0-0xc0ff]
  668. 3.22 s [vm-test-run-centjes-e2e-test] client # [ 0.760966] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]
  669. 3.22 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.836895] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]
  670. 3.23 s [vm-test-run-centjes-e2e-test] client # [ 0.764971] pci 0000:00:05.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]
  671. 3.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.840921] pci 0000:00:0b.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]
  672. 3.23 s [vm-test-run-centjes-e2e-test] client # [ 0.769732] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
  673. 3.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.846230] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
  674. 3.24 s [vm-test-run-centjes-e2e-test] client # [ 0.771966] pci 0000:00:06.0: BAR 0 [io 0xc160-0xc17f]
  675. 3.24 s [vm-test-run-centjes-e2e-test] client # [ 0.774965] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]
  676. 3.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.848916] pci 0000:00:0c.0: BAR 0 [io 0xc1c0-0xc1df]
  677. 3.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.850866] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff]
  678. 3.25 s [vm-test-run-centjes-e2e-test] client # [ 0.778965] pci 0000:00:06.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]
  679. 3.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.854391] pci 0000:00:0c.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]
  680. 3.26 s [vm-test-run-centjes-e2e-test] client # [ 0.784659] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
  681. 3.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.859946] ACPI: PCI: Interrupt link LNKA configured for IRQ 10
  682. 3.26 s [vm-test-run-centjes-e2e-test] client # [ 0.786966] pci 0000:00:07.0: BAR 0 [io 0xc180-0xc19f]
  683. 3.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.861137] ACPI: PCI: Interrupt link LNKB configured for IRQ 10
  684. 3.27 s [vm-test-run-centjes-e2e-test] client # [ 0.788965] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]
  685. 3.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.863183] ACPI: PCI: Interrupt link LNKC configured for IRQ 11
  686. 3.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.865108] ACPI: PCI: Interrupt link LNKD configured for IRQ 11
  687. 3.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.867018] ACPI: PCI: Interrupt link LNKS configured for IRQ 9
  688. 3.27 s [vm-test-run-centjes-e2e-test] client # [ 0.794542] pci 0000:00:07.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]
  689. 3.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.868299] iommu: Default domain type: Translated
  690. 3.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.869913] iommu: DMA domain TLB invalidation policy: lazy mode
  691. 3.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.870946] ACPI: bus type USB registered
  692. 3.28 s [vm-test-run-centjes-e2e-test] client # [ 0.799652] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint
  693. 3.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.871936] usbcore: registered new interface driver usbfs
  694. 3.29 s [vm-test-run-centjes-e2e-test] client # [ 0.801965] pci 0000:00:08.0: BAR 0 [io 0xc000-0xc07f]
  695. 3.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.872923] usbcore: registered new interface driver hub
  696. 3.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.873925] usbcore: registered new device driver usb
  697. 3.29 s [vm-test-run-centjes-e2e-test] client # [ 0.803965] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]
  698. 3.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.875228] NetLabel: Initializing
  699. 3.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.875913] NetLabel: domain hash size = 128
  700. 3.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.876913] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO
  701. 3.30 s [vm-test-run-centjes-e2e-test] client # [ 0.808967] pci 0000:00:08.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]
  702. 3.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.877937] NetLabel: unlabeled traffic allowed by default
  703. 3.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.878915] PCI: Using ACPI for IRQ routing
  704. 3.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.880756] pci 0000:00:02.0: vgaarb: setting as boot VGA device
  705. 3.31 s [vm-test-run-centjes-e2e-test] client # [ 0.814066] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
  706. 3.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.880902] pci 0000:00:02.0: vgaarb: bridge control possible
  707. 3.31 s [vm-test-run-centjes-e2e-test] client # [ 0.816968] pci 0000:00:09.0: BAR 0 [io 0xc1a0-0xc1bf]
  708. 3.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.880902] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
  709. 3.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.880916] vgaarb: loaded
  710. 3.31 s [vm-test-run-centjes-e2e-test] client # [ 0.818965] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]
  711. 3.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.881998] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
  712. 3.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.882913] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
  713. 3.32 s [vm-test-run-centjes-e2e-test] client # [ 0.822937] pci 0000:00:09.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]
  714. 3.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.887954] clocksource: Switched to clocksource kvm-clock
  715. 3.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.893210] VFS: Disk quotas dquot_6.6.0
  716. 3.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.895640] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
  717. 3.34 s [vm-test-run-centjes-e2e-test] client # [ 0.824917] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref]
  718. 3.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.899285] pnp: PnP ACPI init
  719. 3.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.901942] pnp: PnP ACPI: found 6 devices
  720. 3.34 s [vm-test-run-centjes-e2e-test] client # [ 0.828691] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint
  721. 3.35 s [vm-test-run-centjes-e2e-test] client # [ 0.831366] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]
  722. 3.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.910385] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
  723. 3.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.915283] clocksource: Switched to clocksource acpi_pm
  724. 3.35 s [vm-test-run-centjes-e2e-test] client # [ 0.834965] pci 0000:00:0a.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]
  725. 3.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.918275] NET: Registered PF_INET protocol family
  726. 3.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.921029] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
  727. 3.36 s [vm-test-run-centjes-e2e-test] client # [ 0.839987] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint
  728. 3.36 s [vm-test-run-centjes-e2e-test] client # [ 0.842965] pci 0000:00:0b.0: BAR 0 [io 0xc0c0-0xc0ff]
  729. 3.37 s [vm-test-run-centjes-e2e-test] client # [ 0.844964] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]
  730. 3.37 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.935242] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
  731. 3.37 s [vm-test-run-centjes-e2e-test] client # [ 0.849576] pci 0000:00:0b.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]
  732. 3.38 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.939780] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
  733. 3.38 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.943634] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
  734. 3.38 s [vm-test-run-centjes-e2e-test] client # [ 0.855227] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
  735. 3.39 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.947549] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)
  736. 3.39 s [vm-test-run-centjes-e2e-test] client # [ 0.857965] pci 0000:00:0c.0: BAR 0 [io 0xc1c0-0xc1df]
  737. 3.39 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.951347] TCP: Hash tables configured (established 8192 bind 8192)
  738. 3.39 s [vm-test-run-centjes-e2e-test] client # [ 0.859965] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff]
  739. 3.39 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.954793] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)
  740. 3.40 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.958498] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
  741. 3.40 s [vm-test-run-centjes-e2e-test] client # [ 0.863537] pci 0000:00:0c.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]
  742. 3.40 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.961796] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
  743. 3.40 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.965333] NET: Registered PF_UNIX/PF_LOCAL protocol family
  744. 3.41 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.968290] NET: Registered PF_XDP protocol family
  745. 3.41 s [vm-test-run-centjes-e2e-test] client # [ 0.869108] ACPI: PCI: Interrupt link LNKA configured for IRQ 10
  746. 3.41 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.970931] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]
  747. 3.41 s [vm-test-run-centjes-e2e-test] client # [ 0.871184] ACPI: PCI: Interrupt link LNKB configured for IRQ 10
  748. 3.41 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.974091] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]
  749. 3.41 s [vm-test-run-centjes-e2e-test] client # [ 0.873288] ACPI: PCI: Interrupt link LNKC configured for IRQ 11
  750. 3.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.977277] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
  751. 3.42 s [vm-test-run-centjes-e2e-test] client # [ 0.875165] ACPI: PCI: Interrupt link LNKD configured for IRQ 11
  752. 3.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.980803] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window]
  753. 3.42 s [vm-test-run-centjes-e2e-test] client # [ 0.877070] ACPI: PCI: Interrupt link LNKS configured for IRQ 9
  754. 3.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.984173] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
  755. 3.42 s [vm-test-run-centjes-e2e-test] client # [ 0.878325] iommu: Default domain type: Translated
  756. 3.43 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.987822] pci 0000:00:01.0: PIIX3: Enabling Passive Release
  757. 3.43 s [vm-test-run-centjes-e2e-test] client # [ 0.879965] iommu: DMA domain TLB invalidation policy: lazy mode
  758. 3.43 s [vm-test-run-centjes-e2e-test] client # [ 0.880994] ACPI: bus type USB registered
  759. 3.43 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.990893] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
  760. 3.43 s [vm-test-run-centjes-e2e-test] client # [ 0.881993] usbcore: registered new interface driver usbfs
  761. 3.43 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.995330] ACPI: \_SB_.LNKD: Enabled at IRQ 11
  762. 3.43 s [vm-test-run-centjes-e2e-test] client # [ 0.882979] usbcore: registered new interface driver hub
  763. 3.44 s [vm-test-run-centjes-e2e-test] client # [ 0.883977] usbcore: registered new device driver usb
  764. 3.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 0.999751] PCI: CLS 0 bytes, default 64
  765. 3.44 s [vm-test-run-centjes-e2e-test] client # [ 0.885267] NetLabel: Initializing
  766. 3.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.001916] Trying to unpack rootfs image as initramfs...
  767. 3.44 s [vm-test-run-centjes-e2e-test] client # [ 0.885965] NetLabel: domain hash size = 128
  768. 3.44 s [vm-test-run-centjes-e2e-test] client # [ 0.886965] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO
  769. 3.45 s [vm-test-run-centjes-e2e-test] client # [ 0.887989] NetLabel: unlabeled traffic allowed by default
  770. 3.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.006804] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns
  771. 3.45 s [vm-test-run-centjes-e2e-test] client # [ 0.888967] PCI: Using ACPI for IRQ routing
  772. 3.45 s [vm-test-run-centjes-e2e-test] client # [ 0.890806] pci 0000:00:02.0: vgaarb: setting as boot VGA device
  773. 3.45 s [vm-test-run-centjes-e2e-test] client # [ 0.890955] pci 0000:00:02.0: vgaarb: bridge control possible
  774. 3.46 s [vm-test-run-centjes-e2e-test] client # [ 0.890955] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
  775. 3.46 s [vm-test-run-centjes-e2e-test] client # [ 0.890974] vgaarb: loaded
  776. 3.46 s [vm-test-run-centjes-e2e-test] client # [ 0.892048] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
  777. 3.47 s [vm-test-run-centjes-e2e-test] client # [ 0.892966] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
  778. 3.47 s [vm-test-run-centjes-e2e-test] client # [ 0.900027] clocksource: Switched to clocksource kvm-clock
  779. 3.48 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.039243] Initialise system trusted keyrings
  780. 3.48 s [vm-test-run-centjes-e2e-test] client # [ 0.904031] VFS: Disk quotas dquot_6.6.0
  781. 3.48 s [vm-test-run-centjes-e2e-test] client # [ 0.906179] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
  782. 3.48 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.044807] workingset: timestamp_bits=40 max_order=18 bucket_order=0
  783. 3.48 s [vm-test-run-centjes-e2e-test] client # [ 0.909729] pnp: PnP ACPI init
  784. 3.49 s [vm-test-run-centjes-e2e-test] client # [ 0.911976] pnp: PnP ACPI: found 6 devices
  785. 3.50 s [vm-test-run-centjes-e2e-test] client # [ 0.920172] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
  786. 3.50 s [vm-test-run-centjes-e2e-test] client # [ 0.924759] clocksource: Switched to clocksource acpi_pm
  787. 3.50 s [vm-test-run-centjes-e2e-test] client # [ 0.927479] NET: Registered PF_INET protocol family
  788. 3.51 s [vm-test-run-centjes-e2e-test] client # [ 0.930134] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
  789. 3.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.071755] Key type asymmetric registered
  790. 3.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.076792] Asymmetric key parser 'x509' registered
  791. 3.52 s [vm-test-run-centjes-e2e-test] client # [ 0.944189] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
  792. 3.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.084822] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
  793. 3.52 s [vm-test-run-centjes-e2e-test] client # [ 0.948466] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
  794. 3.53 s [vm-test-run-centjes-e2e-test] client # [ 0.952500] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
  795. 3.53 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.093970] io scheduler mq-deadline registered
  796. 3.53 s [vm-test-run-centjes-e2e-test] client # [ 0.956554] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)
  797. 3.53 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.096334] io scheduler kyber registered
  798. 3.54 s [vm-test-run-centjes-e2e-test] client # [ 0.960249] TCP: Hash tables configured (established 8192 bind 8192)
  799. 3.54 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.100100] Freeing initrd memory: 12300K
  800. 3.54 s [vm-test-run-centjes-e2e-test] client # [ 0.963615] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)
  801. 3.54 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.102809] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
  802. 3.54 s [vm-test-run-centjes-e2e-test] client # [ 0.967552] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
  803. 3.55 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.106591] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
  804. 3.55 s [vm-test-run-centjes-e2e-test] client # [ 0.971064] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
  805. 3.55 s [vm-test-run-centjes-e2e-test] client # [ 0.974810] NET: Registered PF_UNIX/PF_LOCAL protocol family
  806. 3.55 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.113354] Linux agpgart interface v0.103
  807. 3.55 s [vm-test-run-centjes-e2e-test] client # [ 0.977605] NET: Registered PF_XDP protocol family
  808. 3.55 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.115749] ACPI: bus type drm_connector registered
  809. 3.56 s [vm-test-run-centjes-e2e-test] client # [ 0.980172] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]
  810. 3.56 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.118885] usbcore: registered new interface driver usbserial_generic
  811. 3.56 s [vm-test-run-centjes-e2e-test] client # [ 0.983304] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]
  812. 3.56 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.122639] usbserial: USB Serial support registered for generic
  813. 3.56 s [vm-test-run-centjes-e2e-test] client # [ 0.986472] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
  814. 3.56 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.125865] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled
  815. 3.56 s [vm-test-run-centjes-e2e-test] client # [ 0.989906] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window]
  816. 3.57 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.129859] drop_monitor: Initializing network drop monitor service
  817. 3.57 s [vm-test-run-centjes-e2e-test] client # [ 0.993264] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
  818. 3.57 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.133286] NET: Registered PF_INET6 protocol family
  819. 3.57 s [vm-test-run-centjes-e2e-test] client # [ 0.996839] pci 0000:00:01.0: PIIX3: Enabling Passive Release
  820. 3.57 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.136785] Segment Routing with IPv6
  821. 3.57 s [vm-test-run-centjes-e2e-test] client # [ 0.999845] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
  822. 3.58 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.138833] In-situ OAM (IOAM) with IPv6
  823. 3.58 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.141331] IPI shorthand broadcast: enabled
  824. 3.58 s [vm-test-run-centjes-e2e-test] client # [ 1.004292] ACPI: \_SB_.LNKD: Enabled at IRQ 11
  825. 3.58 s [vm-test-run-centjes-e2e-test] client # [ 1.008756] PCI: CLS 0 bytes, default 64
  826. 3.58 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.146517] registered taskstats version 1
  827. 3.59 s [vm-test-run-centjes-e2e-test] client # [ 1.011052] Trying to unpack rootfs image as initramfs...
  828. 3.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.149048] Loading compiled-in X.509 certificates
  829. 3.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.156572] Demotion targets for Node 0: null
  830. 3.59 s [vm-test-run-centjes-e2e-test] client # [ 1.016830] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x31024b3bec5, max_idle_ns: 440795366697 ns
  831. 3.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.159056] Key type .fscrypt registered
  832. 3.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.161190] Key type fscrypt-provisioning registered
  833. 3.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.163865] PM: Magic number: 6:161:897
  834. 3.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.166526] RAS: Correctable Errors collector initialized.
  835. 3.61 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.172337] Unstable clock detected, switching default tracing clock to "global"
  836. 3.61 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.172337] If you want to keep using the local clock, then add:
  837. 3.61 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.172337] "trace_clock=local"
  838. 3.62 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.172337] on the kernel command line
  839. 3.62 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.181901] clk: Disabling unused clocks
  840. 3.62 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.184053] PM: genpd: Disabling unused power domains
  841. 3.62 s [vm-test-run-centjes-e2e-test] client # [ 1.050261] Initialise system trusted keyrings
  842. 3.62 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.187818] Freeing unused decrypted memory: 2028K
  843. 3.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.190785] Freeing unused kernel image (initmem) memory: 3408K
  844. 3.63 s [vm-test-run-centjes-e2e-test] client # [ 1.055951] workingset: timestamp_bits=40 max_order=18 bucket_order=0
  845. 3.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.193908] Write protecting the kernel read-only data: 30720k
  846. 3.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.197610] Freeing unused kernel image (rodata/data gap) memory: 1756K
  847. 3.66 s [vm-test-run-centjes-e2e-test] client # [ 1.081824] Key type asymmetric registered
  848. 3.66 s [vm-test-run-centjes-e2e-test] client # [ 1.087863] Asymmetric key parser 'x509' registered
  849. 3.67 s [vm-test-run-centjes-e2e-test] client # [ 1.094776] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
  850. 3.68 s [vm-test-run-centjes-e2e-test] client # [ 1.103899] io scheduler mq-deadline registered
  851. 3.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.241006] x86/mm: Checked W+X mappings: passed, no W+X pages found.
  852. 3.68 s [vm-test-run-centjes-e2e-test] client # [ 1.106242] io scheduler kyber registered
  853. 3.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.244356] Run /init as init process
  854. 3.69 s [vm-test-run-centjes-e2e-test] client # [ 1.111410] Freeing initrd memory: 12300K
  855. 3.69 s [vm-test-run-centjes-e2e-test] client # [ 1.114002] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
  856. 3.69 s [vm-test-run-centjes-e2e-test] client # [ 1.117517] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
  857. 3.70 s [vm-test-run-centjes-e2e-test] client # [ 1.122459] Linux agpgart interface v0.103
  858. 3.70 s [vm-test-run-centjes-e2e-test] client # [ 1.124751] ACPI: bus type drm_connector registered
  859. 3.70 s [vm-test-run-centjes-e2e-test] client # [ 1.127509] usbcore: registered new interface driver usbserial_generic
  860. 3.71 s [vm-test-run-centjes-e2e-test] client # [ 1.130830] usbserial: USB Serial support registered for generic
  861. 3.71 s [vm-test-run-centjes-e2e-test] client # [ 1.133838] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled
  862. 3.71 s [vm-test-run-centjes-e2e-test] client # [ 1.137587] drop_monitor: Initializing network drop monitor service
  863. 3.71 s [vm-test-run-centjes-e2e-test] client # [ 1.140811] NET: Registered PF_INET6 protocol family
  864. 3.72 s [vm-test-run-centjes-e2e-test] client # [ 1.144031] Segment Routing with IPv6
  865. 3.72 s [vm-test-run-centjes-e2e-test] client # [ 1.146126] In-situ OAM (IOAM) with IPv6
  866. 3.72 s [vm-test-run-centjes-e2e-test] client # [ 1.148535] IPI shorthand broadcast: enabled
  867. 3.73 s [vm-test-run-centjes-e2e-test] client # [ 1.153063] registered taskstats version 1
  868. 3.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.289051] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev
  869. 3.73 s [vm-test-run-centjes-e2e-test] client # [ 1.155487] Loading compiled-in X.509 certificates
  870. 3.74 s [vm-test-run-centjes-e2e-test] client # [ 1.162825] Demotion targets for Node 0: null
  871. 3.74 s [vm-test-run-centjes-e2e-test] client # [ 1.165139] Key type .fscrypt registered
  872. 3.74 s [vm-test-run-centjes-e2e-test] client # [ 1.167305] Key type fscrypt-provisioning registered
  873. 3.74 s [vm-test-run-centjes-e2e-test] client # [ 1.170087] PM: Magic number: 6:161:897
  874. 3.75 s [vm-test-run-centjes-e2e-test] client # [ 1.172942] RAS: Correctable Errors collector initialized.
  875. 3.76 s [vm-test-run-centjes-e2e-test] client # [ 1.178597] Unstable clock detected, switching default tracing clock to "global"
  876. 3.76 s [vm-test-run-centjes-e2e-test] client # [ 1.178597] If you want to keep using the local clock, then add:
  877. 3.76 s [vm-test-run-centjes-e2e-test] client # [ 1.178597] "trace_clock=local"
  878. 3.76 s [vm-test-run-centjes-e2e-test] client # [ 1.178597] on the kernel command line
  879. 3.76 s [vm-test-run-centjes-e2e-test] client # [ 1.187957] clk: Disabling unused clocks
  880. 3.76 s [vm-test-run-centjes-e2e-test] client # [ 1.190026] PM: genpd: Disabling unused power domains
  881. 3.77 s [vm-test-run-centjes-e2e-test] client # [ 1.193558] Freeing unused decrypted memory: 2028K
  882. 3.77 s [vm-test-run-centjes-e2e-test] client # [ 1.196362] Freeing unused kernel image (initmem) memory: 3408K
  883. 3.77 s [vm-test-run-centjes-e2e-test] client # [ 1.199320] Write protecting the kernel read-only data: 30720k
  884. 3.78 s [vm-test-run-centjes-e2e-test] client # [ 1.202534] Freeing unused kernel image (rodata/data gap) memory: 1756K
  885. 3.82 s [vm-test-run-centjes-e2e-test] client # [ 1.245610] x86/mm: Checked W+X mappings: passed, no W+X pages found.
  886. 3.82 s [vm-test-run-centjes-e2e-test] client # [ 1.249081] Run /init as init process
  887. 3.87 s [vm-test-run-centjes-e2e-test] client # [ 1.295108] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev
  888. 3.91 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.469475] ACPI: \_SB_.LNKC: Enabled at IRQ 10
  889. 3.91 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.473206] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
  890. 3.92 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.485982] SCSI subsystem initialized
  891. 3.93 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.494894] uhci_hcd 0000:00:01.2: UHCI Host Controller
  892. 3.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.501407] serio: i8042 KBD port at 0x60,0x64 irq 1
  893. 3.95 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.514748] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
  894. 3.96 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.522922] serio: i8042 AUX port at 0x60,0x64 irq 12
  895. 3.96 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.527165] ACPI: \_SB_.LNKA: Enabled at IRQ 10
  896. 3.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.532762] uhci_hcd 0000:00:01.2: detected 2 ports
  897. 3.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.544015] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100
  898. 3.99 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.548460] ACPI: \_SB_.LNKB: Enabled at IRQ 11
  899. 4.00 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.556892] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.12
  900. 4.00 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.560897] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
  901. 4.00 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.564441] usb usb1: Product: UHCI Host Controller
  902. 4.00 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.567019] usb usb1: Manufacturer: Linux 6.12.62 uhci_hcd
  903. 4.01 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.569745] usb usb1: SerialNumber: 0000:00:01.2
  904. 4.01 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.577487] scsi host0: ata_piix
  905. 4.02 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.585231] scsi host1: ata_piix
  906. 4.03 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.587805] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 lpm-pol 0
  907. 4.03 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.591478] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0
  908. 4.04 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.600809] hub 1-0:1.0: USB hub found
  909. 4.04 s [vm-test-run-centjes-e2e-test] client # [ 1.463500] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
  910. 4.04 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.606870] hub 1-0:1.0: 2 ports detected
  911. 4.05 s [vm-test-run-centjes-e2e-test] client # [ 1.476458] SCSI subsystem initialized
  912. 4.07 s [vm-test-run-centjes-e2e-test] client # [ 1.492382] serio: i8042 KBD port at 0x60,0x64 irq 1
  913. 4.07 s [vm-test-run-centjes-e2e-test] client # [ 1.496452] ACPI: \_SB_.LNKC: Enabled at IRQ 10
  914. 4.08 s [vm-test-run-centjes-e2e-test] client # [ 1.507260] serio: i8042 AUX port at 0x60,0x64 irq 12
  915. 4.09 s [vm-test-run-centjes-e2e-test] client # [ 1.511240] uhci_hcd 0000:00:01.2: UHCI Host Controller
  916. 4.10 s [vm-test-run-centjes-e2e-test] client # [ 1.525365] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
  917. 4.11 s [vm-test-run-centjes-e2e-test] client # [ 1.536205] uhci_hcd 0000:00:01.2: detected 2 ports
  918. 4.11 s [vm-test-run-centjes-e2e-test] client # [ 1.540357] ACPI: \_SB_.LNKA: Enabled at IRQ 10
  919. 4.12 s [vm-test-run-centjes-e2e-test] client # [ 1.544531] scsi host0: ata_piix
  920. 4.12 s [vm-test-run-centjes-e2e-test] client # [ 1.548081] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100
  921. 4.13 s [vm-test-run-centjes-e2e-test] client # [ 1.551851] scsi host1: ata_piix
  922. 4.13 s [vm-test-run-centjes-e2e-test] client # [ 1.556270] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 lpm-pol 0
  923. 4.14 s [vm-test-run-centjes-e2e-test] client # [ 1.560047] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0
  924. 4.14 s [vm-test-run-centjes-e2e-test] client # [ 1.567167] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.12
  925. 4.15 s [vm-test-run-centjes-e2e-test] client # [ 1.571159] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
  926. 4.15 s [vm-test-run-centjes-e2e-test] client # [ 1.574707] usb usb1: Product: UHCI Host Controller
  927. 4.15 s [vm-test-run-centjes-e2e-test] client # [ 1.577144] usb usb1: Manufacturer: Linux 6.12.62 uhci_hcd
  928. 4.15 s [vm-test-run-centjes-e2e-test] client # [ 1.579879] usb usb1: SerialNumber: 0000:00:01.2
  929. 4.16 s [vm-test-run-centjes-e2e-test] client # [ 1.582602] ACPI: \_SB_.LNKB: Enabled at IRQ 11
  930. 4.17 s [vm-test-run-centjes-e2e-test] client # [ 1.596155] hub 1-0:1.0: USB hub found
  931. 4.18 s [vm-test-run-centjes-e2e-test] client # [ 1.601134] hub 1-0:1.0: 2 ports detected
  932. 4.19 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.755082] ata2: found unknown device (class 0)
  933. 4.20 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.758893] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
  934. 4.20 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.764075] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5
  935. 4.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.833755] usb 1-1: new full-speed USB device number 2 using uhci_hcd
  936. 4.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.852227] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
  937. 4.30 s [vm-test-run-centjes-e2e-test] client # [ 1.721939] ata2: found unknown device (class 0)
  938. 4.30 s [vm-test-run-centjes-e2e-test] client # [ 1.725844] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
  939. 4.31 s [vm-test-run-centjes-e2e-test] client # [ 1.731045] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5
  940. 4.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.888813] virtio_blk virtio5: 1/0/0 default/read/poll queues
  941. 4.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.906355] netfs: FS-Cache loaded
  942. 4.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.918966] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)
  943. 4.38 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.942860] 9pnet: Installing 9P2000 support
  944. 4.39 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.949807] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
  945. 4.39 s [vm-test-run-centjes-e2e-test] docsserver # [ 1.953190] cdrom: Uniform CD-ROM driver Revision: 3.20
  946. 4.40 s [vm-test-run-centjes-e2e-test] client # [ 1.819891] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
  947. 4.40 s [vm-test-run-centjes-e2e-test] client # [ 1.827750] usb 1-1: new full-speed USB device number 2 using uhci_hcd
  948. 4.42 s [vm-test-run-centjes-e2e-test] client # [ 1.843557] virtio_blk virtio5: 1/0/0 default/read/poll queues
  949. 4.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.007799] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00
  950. 4.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.012031] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10
  951. 4.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.016210] usb 1-1: Product: QEMU USB Tablet
  952. 4.45 s [vm-test-run-centjes-e2e-test] client # [ 1.878377] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)
  953. 4.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.018761] usb 1-1: Manufacturer: QEMU
  954. 4.46 s [vm-test-run-centjes-e2e-test] client # [ 1.883260] netfs: FS-Cache loaded
  955. 4.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.020920] usb 1-1: SerialNumber: 28754-0000:00:01.2-1
  956. 4.47 s [vm-test-run-centjes-e2e-test] client # [ 1.898356] 9pnet: Installing 9P2000 support
  957. 4.48 s [vm-test-run-centjes-e2e-test] client # [ 1.905931] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
  958. 4.48 s [vm-test-run-centjes-e2e-test] client # [ 1.909208] cdrom: Uniform CD-ROM driver Revision: 3.20
  959. 4.49 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.041755] hid: raw HID events driver (C) Jiri Kosina
  960. 4.50 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.067289] usbcore: registered new interface driver usbhid
  961. 4.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.070325] usbhid: USB HID core driver
  962. 4.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.077010] 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
  963. 4.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.082963] 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
  964. 4.58 s [vm-test-run-centjes-e2e-test] client # [ 2.002509] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00
  965. 4.58 s [vm-test-run-centjes-e2e-test] client # [ 2.006752] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10
  966. 4.58 s [vm-test-run-centjes-e2e-test] client # [ 2.010354] usb 1-1: Product: QEMU USB Tablet
  967. 4.59 s [vm-test-run-centjes-e2e-test] client # [ 2.012789] usb 1-1: Manufacturer: QEMU
  968. 4.59 s [vm-test-run-centjes-e2e-test] client # [ 2.014855] usb 1-1: SerialNumber: 28754-0000:00:01.2-1
  969. 4.61 s [vm-test-run-centjes-e2e-test] client # [ 2.029150] hid: raw HID events driver (C) Jiri Kosina
  970. 4.64 s [vm-test-run-centjes-e2e-test] client # [ 2.065013] usbcore: registered new interface driver usbhid
  971. 4.64 s [vm-test-run-centjes-e2e-test] client # [ 2.067922] usbhid: USB HID core driver
  972. 4.65 s [vm-test-run-centjes-e2e-test] client # [ 2.074114] 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
  973. 4.66 s [vm-test-run-centjes-e2e-test] client # [ 2.079913] 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
  974. 4.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.254610] EXT4-fs (vda): mounted filesystem 6639912a-c4ee-41cf-8fed-fac43081e889 r/w with ordered data mode. Quota mode: none.
  975. 4.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.283265] 9p: Installing v9fs 9p2000 file system support
  976. 4.78 s [vm-test-run-centjes-e2e-test] client # [ 2.202829] EXT4-fs (vda): mounted filesystem 656f52a2-c5ec-4f87-b164-450992204d1a r/w with ordered data mode. Quota mode: none.
  977. 4.80 s [vm-test-run-centjes-e2e-test] client # [ 2.224870] 9p: Installing v9fs 9p2000 file system support
  978. 4.99 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.553793] EXT4-fs (vda): re-mounted 6639912a-c4ee-41cf-8fed-fac43081e889.
  979. 5.00 s [vm-test-run-centjes-e2e-test] docsserver # [ 2.558810] booting system configuration /nix/store/iq1h1hr53bq6xw39wn2hg1rbi77vrj15-nixos-system-docsserver-test
  980. 5.03 s [vm-test-run-centjes-e2e-test] client # [ 2.455800] EXT4-fs (vda): re-mounted 656f52a2-c5ec-4f87-b164-450992204d1a.
  981. 5.04 s [vm-test-run-centjes-e2e-test] client # [ 2.460867] booting system configuration /nix/store/001k9r4jinw78ih5x5hxggb82ynlbkqn-nixos-system-client-test
  982. 6.73 s [vm-test-run-centjes-e2e-test] client # [ 4.151766] systemd[1]: Inserted module 'autofs4'
  983. 6.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 4.289157] systemd[1]: Inserted module 'autofs4'
  984. 6.79 s [vm-test-run-centjes-e2e-test] client # [ 4.199388] 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)
  985. 6.79 s [vm-test-run-centjes-e2e-test] client # [ 4.214817] systemd[1]: Detected virtualization kvm.
  986. 6.79 s [vm-test-run-centjes-e2e-test] client # [ 4.217406] systemd[1]: Detected architecture x86-64.
  987. 6.79 s [vm-test-run-centjes-e2e-test] docsserver # [ 4.340425] 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)
  988. 6.79 s [vm-test-run-centjes-e2e-test] client # [ 4.220178] systemd[1]: Detected first boot.
  989. 6.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 4.357961] systemd[1]: Detected virtualization kvm.
  990. 6.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 4.361053] systemd[1]: Detected architecture x86-64.
  991. 6.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 4.364612] systemd[1]: Detected first boot.
  992. 6.80 s [vm-test-run-centjes-e2e-test] client # [ 4.226810] systemd[1]: Initializing machine ID from random generator.
  993. 6.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 4.371059] systemd[1]: Initializing machine ID from random generator.
  994. 6.83 s [vm-test-run-centjes-e2e-test] client # [ 4.257396] systemd[1]: Hostname set to <client>.
  995. 6.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 4.399031] systemd[1]: Hostname set to <docsserver>.
  996. 6.95 s [vm-test-run-centjes-e2e-test] client # [ 4.371753] systemd[1]: bpf-restrict-fs: LSM BPF program attached
  997. 6.95 s [vm-test-run-centjes-e2e-test] docsserver # [ 4.513077] systemd[1]: bpf-restrict-fs: LSM BPF program attached
  998. 7.11 s [vm-test-run-centjes-e2e-test] client # [ 4.533388] systemd[1]: Applying preset policy.
  999. 7.11 s [vm-test-run-centjes-e2e-test] docsserver # [ 4.674782] systemd[1]: Applying preset policy.
  1000. 7.67 s [vm-test-run-centjes-e2e-test] client # [ 5.098807] systemd[1]: Populated /etc with preset unit settings.
  1001. 7.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.239894] systemd[1]: Populated /etc with preset unit settings.
  1002. 8.08 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.643615] systemd[1]: /etc/systemd/system/centjes-docs-site-production.service:3: Invalid URL, ignoring: /nix/store/9m6q429jr0nm3zq62h9yqghypq99rpjx-settings-check
  1003. 8.18 s [vm-test-run-centjes-e2e-test] client # [ 5.601908] systemd[1]: Queued start job for default target Multi-User System.
  1004. 8.19 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.756180] systemd[1]: Queued start job for default target Multi-User System.
  1005. 8.21 s [vm-test-run-centjes-e2e-test] client # [ 5.634476] systemd[1]: Created slice Slice /system/getty.
  1006. 8.21 s [vm-test-run-centjes-e2e-test] client # [ 5.637487] systemd[1]: Created slice Slice /system/modprobe.
  1007. 8.21 s [vm-test-run-centjes-e2e-test] client # [ 5.640485] systemd[1]: Created slice User and Session Slice.
  1008. 8.22 s [vm-test-run-centjes-e2e-test] client # [ 5.643281] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
  1009. 8.22 s [vm-test-run-centjes-e2e-test] client # [ 5.646411] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
  1010. 8.22 s [vm-test-run-centjes-e2e-test] client # [ 5.649631] systemd[1]: Expecting device /dev/hvc0...
  1011. 8.23 s [vm-test-run-centjes-e2e-test] client # [ 5.651809] systemd[1]: Expecting device /dev/ttyS0...
  1012. 8.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.788868] systemd[1]: Created slice Slice /system/getty.
  1013. 8.23 s [vm-test-run-centjes-e2e-test] client # [ 5.653867] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...
  1014. 8.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.792077] systemd[1]: Created slice Slice /system/modprobe.
  1015. 8.23 s [vm-test-run-centjes-e2e-test] client # [ 5.656496] systemd[1]: Reached target Local Encrypted Volumes.
  1016. 8.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.795535] systemd[1]: Created slice User and Session Slice.
  1017. 8.23 s [vm-test-run-centjes-e2e-test] client # [ 5.658867] systemd[1]: Reached target Virtual Machines and Containers.
  1018. 8.24 s [vm-test-run-centjes-e2e-test] client # [ 5.661430] systemd[1]: Reached target Path Units.
  1019. 8.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.798501] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
  1020. 8.24 s [vm-test-run-centjes-e2e-test] client # [ 5.663480] systemd[1]: Reached target Remote File Systems.
  1021. 8.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.801911] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
  1022. 8.24 s [vm-test-run-centjes-e2e-test] client # [ 5.665821] systemd[1]: Reached target Slice Units.
  1023. 8.24 s [vm-test-run-centjes-e2e-test] client # [ 5.667863] systemd[1]: Reached target Swaps.
  1024. 8.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.805424] systemd[1]: Expecting device /dev/hvc0...
  1025. 8.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.807787] systemd[1]: Expecting device /dev/ttyS0...
  1026. 8.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.810375] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...
  1027. 8.25 s [vm-test-run-centjes-e2e-test] client # [ 5.673460] systemd[1]: Listening on Process Core Dump Socket.
  1028. 8.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.813103] systemd[1]: Reached target Local Encrypted Volumes.
  1029. 8.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.815494] systemd[1]: Reached target Virtual Machines and Containers.
  1030. 8.25 s [vm-test-run-centjes-e2e-test] client # [ 5.678214] systemd[1]: Listening on Credential Encryption/Decryption.
  1031. 8.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.818107] systemd[1]: Reached target Path Units.
  1032. 8.26 s [vm-test-run-centjes-e2e-test] client # [ 5.681262] systemd[1]: Listening on Journal Audit Socket.
  1033. 8.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.820209] systemd[1]: Reached target Remote File Systems.
  1034. 8.26 s [vm-test-run-centjes-e2e-test] client # [ 5.683795] systemd[1]: Listening on Journal Socket (/dev/log).
  1035. 8.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.822603] systemd[1]: Reached target Slice Units.
  1036. 8.26 s [vm-test-run-centjes-e2e-test] client # [ 5.686237] systemd[1]: Listening on Journal Sockets.
  1037. 8.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.824866] systemd[1]: Reached target Swaps.
  1038. 8.26 s [vm-test-run-centjes-e2e-test] client # [ 5.688818] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.
  1039. 8.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.830781] systemd[1]: Listening on Process Core Dump Socket.
  1040. 8.27 s [vm-test-run-centjes-e2e-test] client # [ 5.691799] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
  1041. 8.27 s [vm-test-run-centjes-e2e-test] client # [ 5.695957] systemd[1]: Listening on udev Control Socket.
  1042. 8.27 s [vm-test-run-centjes-e2e-test] client # [ 5.698273] systemd[1]: Listening on udev Kernel Socket.
  1043. 8.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.835421] systemd[1]: Listening on Credential Encryption/Decryption.
  1044. 8.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.838516] systemd[1]: Listening on Journal Audit Socket.
  1045. 8.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.841035] systemd[1]: Listening on Journal Socket (/dev/log).
  1046. 8.28 s [vm-test-run-centjes-e2e-test] client # [ 5.704618] systemd[1]: Mounting Huge Pages File System...
  1047. 8.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.843614] systemd[1]: Listening on Journal Sockets.
  1048. 8.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.846211] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.
  1049. 8.28 s [vm-test-run-centjes-e2e-test] client # [ 5.709850] systemd[1]: Mounting POSIX Message Queue File System...
  1050. 8.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.849195] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
  1051. 8.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.853618] systemd[1]: Listening on udev Control Socket.
  1052. 8.29 s [vm-test-run-centjes-e2e-test] client # [ 5.716919] systemd[1]: Mounting Kernel Debug File System...
  1053. 8.29 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.856014] systemd[1]: Listening on udev Kernel Socket.
  1054. 8.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.862289] systemd[1]: Mounting Huge Pages File System...
  1055. 8.30 s [vm-test-run-centjes-e2e-test] client # [ 5.729903] systemd[1]: Mounting Kernel Trace File System...
  1056. 8.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.868358] systemd[1]: Mounting POSIX Message Queue File System...
  1057. 8.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.876901] systemd[1]: Mounting Kernel Debug File System...
  1058. 8.32 s [vm-test-run-centjes-e2e-test] client # [ 5.744892] systemd[1]: Starting Create List of Static Device Nodes...
  1059. 8.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.890388] systemd[1]: Mounting Kernel Trace File System...
  1060. 8.33 s [vm-test-run-centjes-e2e-test] client # [ 5.754877] systemd[1]: Load Kernel Module 9pnet_virtio was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!9pnet_virtio).
  1061. 8.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.907380] systemd[1]: Starting Create List of Static Device Nodes...
  1062. 8.35 s [vm-test-run-centjes-e2e-test] client # [ 5.777880] systemd[1]: Starting Load Kernel Module configfs...
  1063. 8.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.917078] systemd[1]: Load Kernel Module 9pnet_virtio was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!9pnet_virtio).
  1064. 8.36 s [vm-test-run-centjes-e2e-test] client # [ 5.780983] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm).
  1065. 8.36 s [vm-test-run-centjes-e2e-test] client # [ 5.790325] systemd[1]: Starting Load Kernel Module efi_pstore...
  1066. 8.37 s [vm-test-run-centjes-e2e-test] client # [ 5.798813] systemd[1]: Starting Load Kernel Module fuse...
  1067. 8.38 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.940905] systemd[1]: Starting Load Kernel Module configfs...
  1068. 8.38 s [vm-test-run-centjes-e2e-test] client # [ 5.804920] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
  1069. 8.38 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.944963] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm).
  1070. 8.39 s [vm-test-run-centjes-e2e-test] client # [ 5.813355] 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).
  1071. 8.39 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.955834] systemd[1]: Starting Load Kernel Module efi_pstore...
  1072. 8.40 s [vm-test-run-centjes-e2e-test] client # [ 5.826904] systemd[1]: Starting Journal Service...
  1073. 8.40 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.965409] systemd[1]: Starting Load Kernel Module fuse...
  1074. 8.41 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.970784] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
  1075. 8.41 s [vm-test-run-centjes-e2e-test] client # [ 5.836256] systemd[1]: Starting Load Kernel Modules...
  1076. 8.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.979090] 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).
  1077. 8.42 s [vm-test-run-centjes-e2e-test] client # [ 5.846864] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...
  1078. 8.43 s [vm-test-run-centjes-e2e-test] client # [ 5.857204] systemd[1]: Starting Remount Root and Kernel File Systems...
  1079. 8.43 s [vm-test-run-centjes-e2e-test] docsserver # [ 5.997311] systemd[1]: Starting Journal Service...
  1080. 8.44 s [vm-test-run-centjes-e2e-test] client # [ 5.864847] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
  1081. 8.44 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.006222] systemd[1]: Starting Load Kernel Modules...
  1082. 8.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.016545] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...
  1083. 8.46 s [vm-test-run-centjes-e2e-test] client # [ 5.880974] systemd[1]: Starting Coldplug All udev Devices...
  1084. 8.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.026062] systemd[1]: Starting Remount Root and Kernel File Systems...
  1085. 8.47 s [vm-test-run-centjes-e2e-test] client # [ 5.893389] systemd[1]: Mounted Huge Pages File System.
  1086. 8.47 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.032426] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
  1087. 8.47 s [vm-test-run-centjes-e2e-test] client # [ 5.900083] systemd[1]: Mounted POSIX Message Queue File System.
  1088. 8.48 s [vm-test-run-centjes-e2e-test] client # [ 5.906004] systemd[1]: Mounted Kernel Debug File System.
  1089. 8.49 s [vm-test-run-centjes-e2e-test] client # [ 5.912140] systemd[1]: Mounted Kernel Trace File System.
  1090. 8.49 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.051485] systemd[1]: Starting Coldplug All udev Devices...
  1091. 8.50 s [vm-test-run-centjes-e2e-test] client # [ 5.922891] systemd[1]: Finished Create List of Static Device Nodes.
  1092. 8.50 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.063251] systemd[1]: Mounted Huge Pages File System.
  1093. 8.50 s [vm-test-run-centjes-e2e-test] client # [ 5.928430] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
  1094. 8.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.069549] systemd[1]: Mounted POSIX Message Queue File System.
  1095. 8.51 s [vm-test-run-centjes-e2e-test] client # [ 5.937017] systemd[1]: Finished Load Kernel Module efi_pstore.
  1096. 8.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.076832] systemd[1]: Mounted Kernel Debug File System.
  1097. 8.52 s [vm-test-run-centjes-e2e-test] client # [ 5.944330] systemd[1]: modprobe@configfs.service: Deactivated successfully.
  1098. 8.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.082615] systemd[1]: Mounted Kernel Trace File System.
  1099. 8.53 s [vm-test-run-centjes-e2e-test] client # [ 5.953019] systemd[1]: Finished Load Kernel Module configfs.
  1100. 8.53 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.090452] systemd[1]: Finished Create List of Static Device Nodes.
  1101. 8.53 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.097795] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
  1102. 8.54 s [vm-test-run-centjes-e2e-test] client # [ 5.964852] systemd[1]: Mounting Kernel Configuration File System...
  1103. 8.54 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.106309] systemd[1]: Finished Load Kernel Module efi_pstore.
  1104. 8.55 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.113061] systemd[1]: modprobe@configfs.service: Deactivated successfully.
  1105. 8.55 s [vm-test-run-centjes-e2e-test] client # [ 5.978115] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...
  1106. 8.56 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.122187] systemd[1]: Finished Load Kernel Module configfs.
  1107. 8.57 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.133846] systemd[1]: Mounting Kernel Configuration File System...
  1108. 8.58 s [vm-test-run-centjes-e2e-test] client # [ 6.001501] systemd[1]: Mounted Kernel Configuration File System.
  1109. 8.58 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.147177] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...
  1110. 8.60 s [vm-test-run-centjes-e2e-test] client # [ 6.024170] EXT4-fs (vda): re-mounted 656f52a2-c5ec-4f87-b164-450992204d1a.
  1111. 8.61 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.169028] systemd[1]: Mounted Kernel Configuration File System.
  1112. 8.61 s [vm-test-run-centjes-e2e-test] client # [ 6.034102] fuse: init (API version 7.41)
  1113. 8.62 s [vm-test-run-centjes-e2e-test] client # [ 6.041081] systemd-journald[383]: Collecting audit messages is enabled.
  1114. 8.62 s [vm-test-run-centjes-e2e-test] client # [ 6.044585] loop: module loaded
  1115. 8.63 s [vm-test-run-centjes-e2e-test] client # [ 6.056797] systemd[1]: Finished Remount Root and Kernel File Systems.
  1116. 8.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.195278] fuse: init (API version 7.41)
  1117. 8.64 s [vm-test-run-centjes-e2e-test] client # [ 6.063206] systemd[1]: modprobe@fuse.service: Deactivated successfully.
  1118. 8.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.201830] EXT4-fs (vda): re-mounted 6639912a-c4ee-41cf-8fed-fac43081e889.
  1119. 8.65 s [vm-test-run-centjes-e2e-test] client # [ 6.071473] systemd[1]: Finished Load Kernel Module fuse.
  1120. 8.65 s [vm-test-run-centjes-e2e-test] client # [ 6.079380] systemd[1]: Finished Load Kernel Modules.
  1121. 8.65 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.217269] systemd[1]: modprobe@fuse.service: Deactivated successfully.
  1122. 8.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.222427] loop: module loaded
  1123. 8.66 s [vm-test-run-centjes-e2e-test] client # [ 6.088963] systemd[1]: Starting Firewall...
  1124. 8.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.225984] systemd[1]: Finished Load Kernel Module fuse.
  1125. 8.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.229482] systemd-journald[391]: Collecting audit messages is enabled.
  1126. 8.67 s [vm-test-run-centjes-e2e-test] client # [ 6.093019] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
  1127. 8.67 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.237892] systemd[1]: Mounting FUSE Control File System...
  1128. 8.68 s [vm-test-run-centjes-e2e-test] client # [ 6.107405] systemd[1]: Starting Load/Save OS Random Seed...
  1129. 8.69 s [vm-test-run-centjes-e2e-test] client # [ 6.117902] systemd[1]: Starting Apply Kernel Variables...
  1130. 8.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.255209] systemd[1]: Finished Remount Root and Kernel File Systems.
  1131. 8.70 s [vm-test-run-centjes-e2e-test] client # [ 6.123230] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
  1132. 8.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.267955] systemd[1]: Finished Load Kernel Modules.
  1133. 8.71 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.274500] systemd[1]: Mounted FUSE Control File System.
  1134. 8.72 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.286268] systemd[1]: Starting Firewall...
  1135. 8.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.289858] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
  1136. 8.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.305510] systemd[1]: Starting Load/Save OS Random Seed...
  1137. 8.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.315638] systemd[1]: Starting Apply Kernel Variables...
  1138. 8.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.319817] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).
  1139. 8.79 s [vm-test-run-centjes-e2e-test] client # [ 6.214924] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.
  1140. 8.82 s [vm-test-run-centjes-e2e-test] client # [ 6.241052] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
  1141. 8.83 s [vm-test-run-centjes-e2e-test] client # [ 6.255000] systemd[1]: Starting Create Static Device Nodes in /dev...
  1142. 8.84 s [vm-test-run-centjes-e2e-test] client # [ 6.266104] systemd[1]: Finished Load/Save OS Random Seed.
  1143. 8.85 s [vm-test-run-centjes-e2e-test] client # [ 6.276022] systemd[1]: Finished Apply Kernel Variables.
  1144. 8.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.416412] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
  1145. 8.86 s [vm-test-run-centjes-e2e-test] client # [ 6.284098] systemd[1]: Reached target First Boot Complete.
  1146. 8.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.430010] systemd[1]: Starting Create Static Device Nodes in /dev...
  1147. 8.88 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.438105] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.
  1148. 8.91 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.473986] systemd[1]: Finished Load/Save OS Random Seed.
  1149. 8.92 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.482782] systemd[1]: Reached target First Boot Complete.
  1150. 8.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.508160] systemd[1]: Finished Apply Kernel Variables.
  1151. 8.97 s [vm-test-run-centjes-e2e-test] client # [ 6.400022] systemd[1]: Finished Create Static Device Nodes in /dev.
  1152. 8.98 s [vm-test-run-centjes-e2e-test] client # [ 6.406358] systemd[1]: Reached target Preparation for Local File Systems.
  1153. 8.99 s [vm-test-run-centjes-e2e-test] client # [ 6.417233] systemd[1]: Starting Rule-based Manager for Device Events and Files...
  1154. 9.04 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.598491] systemd[1]: Finished Create Static Device Nodes in /dev.
  1155. 9.04 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.605272] systemd[1]: Reached target Preparation for Local File Systems.
  1156. 9.05 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.616406] systemd[1]: Starting Rule-based Manager for Device Events and Files...
  1157. 9.15 s [vm-test-run-centjes-e2e-test] client # [ 6.572456] systemd[1]: Started Journal Service.
  1158. 9.16 s [vm-test-run-centjes-e2e-test] client # [ 6.165233] systemd-modules-load[384]: Inserted module 'loop'
  1159. 9.17 s [vm-test-run-centjes-e2e-test] client # [ 6.172868] systemd-oomd[385]: [0;1;38:5:185mNo swap; memory pressure usage will be degraded
  1160. 9.19 s [vm-test-run-centjes-e2e-test] client # [ 6.191623] systemd-udevd[411]: Using default interface naming scheme 'v258'.
  1161. 9.20 s [vm-test-run-centjes-e2e-test] client # [ 6.206747] systemd[1]: Starting Flush Journal to Persistent Storage...
  1162. 9.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.791025] systemd[1]: Started Journal Service.
  1163. 9.24 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.388971] systemd-modules-load[392]: Inserted module 'loop'
  1164. 9.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.397937] systemd-oomd[393]: [0;1;38:5:185mNo swap; memory pressure usage will be degraded
  1165. 9.25 s [vm-test-run-centjes-e2e-test] client # [ 6.677512] systemd-journald[383]: Received client request to flush runtime journal.
  1166. 9.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.413689] systemd-udevd[418]: Using default interface naming scheme 'v258'.
  1167. 9.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.430228] systemd[1]: Starting Flush Journal to Persistent Storage...
  1168. 9.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.904236] systemd-journald[391]: Received client request to flush runtime journal.
  1169. 9.46 s [vm-test-run-centjes-e2e-test] client # [ 6.467256] systemd[1]: Finished Coldplug All udev Devices.
  1170. 9.47 s [vm-test-run-centjes-e2e-test] client # [ 6.475672] systemd[1]: Started Rule-based Manager for Device Events and Files.
  1171. 9.48 s [vm-test-run-centjes-e2e-test] client # [ 6.490843] systemd[1]: Finished Flush Journal to Persistent Storage.
  1172. 9.50 s [vm-test-run-centjes-e2e-test] client # [ 6.506291] systemd[1]: Mounting /run/wrappers...
  1173. 9.51 s [vm-test-run-centjes-e2e-test] client # [ 6.513708] systemd[1]: Mounting FUSE Control File System...
  1174. 9.52 s [vm-test-run-centjes-e2e-test] client # [ 6.527143] systemd[1]: Mounted FUSE Control File System.
  1175. 9.53 s [vm-test-run-centjes-e2e-test] client # [ 6.541406] systemd[1]: Mounted /run/wrappers.
  1176. 9.54 s [vm-test-run-centjes-e2e-test] client # [ 6.547324] systemd[1]: Reached target Local File Systems.
  1177. 9.55 s [vm-test-run-centjes-e2e-test] client # [ 6.554411] systemd[1]: Listening on Boot Entries Service Socket.
  1178. 9.56 s [vm-test-run-centjes-e2e-test] client # [ 6.566957] systemd[1]: Starting Create SUID/SGID Wrappers...
  1179. 9.58 s [vm-test-run-centjes-e2e-test] client # [ 6.575242] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.
  1180. 9.59 s [vm-test-run-centjes-e2e-test] client # [ 6.593673] systemd[1]: Starting Save Transient machine-id to Disk...
  1181. 9.60 s [vm-test-run-centjes-e2e-test] client # [ 6.605538] systemd[1]: Starting Create System Files and Directories...
  1182. 9.62 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.768410] systemd[1]: Finished Coldplug All udev Devices.
  1183. 9.65 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.791830] systemd[1]: Started Rule-based Manager for Device Events and Files.
  1184. 9.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.806745] systemd[1]: Mounting /run/wrappers...
  1185. 9.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.821453] systemd[1]: Mounted /run/wrappers.
  1186. 9.67 s [vm-test-run-centjes-e2e-test] client # [ 6.681768] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
  1187. 9.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.830365] systemd[1]: Reached target Local File Systems.
  1188. 9.68 s [vm-test-run-centjes-e2e-test] client # [ 6.692752] systemd[1]: Finished Save Transient machine-id to Disk.
  1189. 9.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.841789] systemd[1]: Listening on Boot Entries Service Socket.
  1190. 9.70 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.855877] systemd[1]: Starting Create SUID/SGID Wrappers...
  1191. 9.73 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.863892] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.
  1192. 9.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.888408] systemd[1]: Starting Save Transient machine-id to Disk...
  1193. 9.74 s [vm-test-run-centjes-e2e-test] client # [ 6.747455] systemd[1]: Finished Create System Files and Directories.
  1194. 9.75 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.900221] systemd[1]: Finished Flush Journal to Persistent Storage.
  1195. 9.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.908798] systemd[1]: Starting Create System Files and Directories...
  1196. 9.76 s [vm-test-run-centjes-e2e-test] client # [ 6.759799] systemd[1]: Starting Rebuild Journal Catalog...
  1197. 9.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.919335] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
  1198. 9.77 s [vm-test-run-centjes-e2e-test] client # [ 6.772770] systemd[1]: Starting Record System Boot/Shutdown in UTMP...
  1199. 9.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.930641] systemd[1]: Finished Save Transient machine-id to Disk.
  1200. 9.84 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.989301] systemd[1]: Finished Create System Files and Directories.
  1201. 9.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 6.999386] systemd[1]: Starting Rebuild Journal Catalog...
  1202. 9.86 s [vm-test-run-centjes-e2e-test] client # [ 6.864155] systemd[1]: Finished Record System Boot/Shutdown in UTMP.
  1203. 9.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.012124] systemd[1]: Starting Record System Boot/Shutdown in UTMP...
  1204. 9.93 s [vm-test-run-centjes-e2e-test] client # [ 6.939466] systemd[1]: Finished Rebuild Journal Catalog.
  1205. 9.94 s [vm-test-run-centjes-e2e-test] client # [ 6.949364] systemd[1]: Starting Update is Completed...
  1206. 9.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.098250] systemd[1]: Finished Record System Boot/Shutdown in UTMP.
  1207. 10.02 s [vm-test-run-centjes-e2e-test] client # [ 7.030650] systemd[1]: Finished Update is Completed.
  1208. 10.03 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.177772] systemd[1]: Finished Rebuild Journal Catalog.
  1209. 10.04 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.187600] systemd[1]: Starting Update is Completed...
  1210. 10.11 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.265667] systemd[1]: Finished Update is Completed.
  1211. 10.16 s [vm-test-run-centjes-e2e-test] client # [ 7.168945] systemd[1]: Found device /dev/hvc0.
  1212. 10.28 s [vm-test-run-centjes-e2e-test] client # [ 7.234812] systemd[1]: Found device /dev/ttyS0.
  1213. 10.28 s [vm-test-run-centjes-e2e-test] client # [ 7.253746] (udev-worker)[491]: [0;1;38:5:185meth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
  1214. 10.28 s [vm-test-run-centjes-e2e-test] client # [ 7.278360] (udev-worker)[491]: Network interface NamePolicy= disabled on kernel command line.
  1215. 10.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.436310] systemd[1]: Found device /dev/hvc0.
  1216. 10.28 s [vm-test-run-centjes-e2e-test] client # [ 7.287825] (udev-worker)[505]: Network interface NamePolicy= disabled on kernel command line.
  1217. 10.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.500904] systemd[1]: Found device /dev/ttyS0.
  1218. 10.39 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.543374] (udev-worker)[509]: Network interface NamePolicy= disabled on kernel command line.
  1219. 10.41 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.556440] (udev-worker)[518]: [0;1;38:5:185meth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
  1220. 10.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.573360] (udev-worker)[518]: Network interface NamePolicy= disabled on kernel command line.
  1221. 10.43 s [vm-test-run-centjes-e2e-test] client # [ 7.437909] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.
  1222. 10.44 s [vm-test-run-centjes-e2e-test] client # [ 7.447843] systemd[1]: Finished Create SUID/SGID Wrappers.
  1223. 10.46 s [vm-test-run-centjes-e2e-test] client # [ 7.458761] systemd[1]: Reached target System Initialization.
  1224. 10.48 s [vm-test-run-centjes-e2e-test] client # [ 7.474766] systemd[1]: Started Discard unused filesystem blocks once a week.
  1225. 10.50 s [vm-test-run-centjes-e2e-test] client # [ 7.498287] systemd[1]: Started Daily Cleanup of Temporary Directories.
  1226. 10.51 s [vm-test-run-centjes-e2e-test] client # [ 7.513576] systemd[1]: Reached target Timer Units.
  1227. 10.52 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.668340] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.
  1228. 10.53 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.683511] systemd[1]: Finished Create SUID/SGID Wrappers.
  1229. 10.54 s [vm-test-run-centjes-e2e-test] client # [ 7.532527] systemd[1]: Listening on D-Bus System Message Bus Socket.
  1230. 10.55 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.696587] systemd[1]: Reached target System Initialization.
  1231. 10.55 s [vm-test-run-centjes-e2e-test] client # [ 7.555636] systemd[1]: Listening on Nix Daemon Socket.
  1232. 10.56 s [vm-test-run-centjes-e2e-test] client # [ 7.571875] systemd[1]: Listening on Hostname Service Socket.
  1233. 10.57 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.709889] systemd[1]: Started Discard unused filesystem blocks once a week.
  1234. 10.58 s [vm-test-run-centjes-e2e-test] client # [ 7.584790] systemd[1]: Reached target Socket Units.
  1235. 10.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.732375] systemd[1]: Started Daily Cleanup of Temporary Directories.
  1236. 10.59 s [vm-test-run-centjes-e2e-test] client # [ 7.600231] systemd[1]: Reached target Basic System.
  1237. 10.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.751409] systemd[1]: Reached target Timer Units.
  1238. 10.61 s [vm-test-run-centjes-e2e-test] client # [ 7.615823] systemd[1]: Started backdoor.service.
  1239. 10.62 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.765173] systemd[1]: Listening on D-Bus System Message Bus Socket.
  1240. 10.63 s [vm-test-run-centjes-e2e-test] client # [ 7.626836] systemd[1]: Starting Import lastlog data into lastlog2 database...
  1241. 10.63 s [vm-test-run-centjes-e2e-test] client # connecting to host...
  1242. 10.64 s [vm-test-run-centjes-e2e-test] client # [ 7.648219] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
  1243. 10.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.781903] systemd[1]: Listening on Nix Daemon Socket.
  1244. 10.66 s [vm-test-run-centjes-e2e-test] client # [ 7.663435] systemd[1]: Started Reset console on configuration changes.
  1245. 10.66 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.805960] systemd[1]: Listening on Hostname Service Socket.
  1246. 10.67 s [vm-test-run-centjes-e2e-test] client # [ 7.679563] systemd[1]: Starting resolvconf update...
  1247. 10.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.823612] systemd[1]: Reached target Socket Units.
  1248. 10.68 s [vm-test-run-centjes-e2e-test] client # [ 7.687586] systemd[1]: Found device Virtio network device.
  1249. 10.69 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.841328] systemd[1]: Reached target Basic System.
  1250. 10.71 s [vm-test-run-centjes-e2e-test] client # [ 7.697673] nsncd[617]: Apr 27 17:53:50.834 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
  1251. 10.71 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.860665] systemd[1]: Started backdoor.service.
  1252. 10.72 s [vm-test-run-centjes-e2e-test] client # [ 7.724321] systemd[1]: Starting D-Bus System Message Bus...
  1253. 10.72 s [vm-test-run-centjes-e2e-test] client # [ 7.735161] systemd[1]: Finished Firewall.
  1254. 10.73 s [vm-test-run-centjes-e2e-test] client # [ 7.740322] systemd[1]: Finished Import lastlog data into lastlog2 database.
  1255. 10.74 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.878764] systemd[1]: Starting Import lastlog data into lastlog2 database...
  1256. 10.74 s [vm-test-run-centjes-e2e-test] client # [ 7.748730] systemd[1]: Started Name Service Cache Daemon (nsncd).
  1257. 10.75 s [vm-test-run-centjes-e2e-test] docsserver # connecting to host...
  1258. 10.76 s [vm-test-run-centjes-e2e-test] client # [ 7.761587] dbus-daemon[620]: dbus[620]: Unknown username "systemd-timesync" in message bus configuration file
  1259. 10.76 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.908436] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
  1260. 10.78 s [vm-test-run-centjes-e2e-test] client # [ 7.779768] systemd[1]: Reached target Host and Network Name Lookups.
  1261. 10.78 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.929380] systemd[1]: Started Reset console on configuration changes.
  1262. 10.79 s [vm-test-run-centjes-e2e-test] client # [ 7.793949] systemd[1]: Reached target User and Group Name Lookups.
  1263. 10.79 s [vm-test-run-centjes-e2e-test] client # [ 7.801315] systemd[1]: Starting User Login Management...
  1264. 10.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.942808] systemd[1]: Starting resolvconf update...
  1265. 10.81 s [vm-test-run-centjes-e2e-test] client # [ 7.818496] systemd[1]: Started D-Bus System Message Bus.
  1266. 10.81 s [vm-test-run-centjes-e2e-test] docsserver: Guest shell says: b'Spawning backdoor root shell...\n'
  1267. 10.83 s [vm-test-run-centjes-e2e-test] docsserver: connected to guest root shell
  1268. 10.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.955653] systemd[1]: Started Centjes docs site production Service.
  1269. 10.83 s [vm-test-run-centjes-e2e-test] docsserver: (connecting took 9.06 seconds)
  1270. 10.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.975951] systemd[1]: Starting D-Bus System Message Bus...
  1271. 10.83 s [vm-test-run-centjes-e2e-test] docsserver: (finished: waiting for the VM to finish booting, in 9.06 seconds)
  1272. 10.84 s [vm-test-run-centjes-e2e-test] client # [ 7.852316] systemd[1]: Stopped target Host and Network Name Lookups.
  1273. 10.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 7.992145] nsncd[621]: Apr 27 17:53:50.970 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
  1274. 10.86 s [vm-test-run-centjes-e2e-test] client # [ 7.865199] systemd[1]: Stopping Host and Network Name Lookups...
  1275. 10.87 s [vm-test-run-centjes-e2e-test] client # [ 7.876744] systemd[1]: Stopped target User and Group Name Lookups.
  1276. 10.87 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.018401] systemd[1]: Finished Import lastlog data into lastlog2 database.
  1277. 10.88 s [vm-test-run-centjes-e2e-test] client # [ 7.885948] systemd[1]: Stopping User and Group Name Lookups...
  1278. 10.88 s [vm-test-run-centjes-e2e-test] client # [ 7.891994] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...
  1279. 10.89 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.031706] systemd[1]: Started Name Service Cache Daemon (nsncd).
  1280. 10.89 s [vm-test-run-centjes-e2e-test] client # [ 7.902142] systemd[1]: nscd.service: Deactivated successfully.
  1281. 10.90 s [vm-test-run-centjes-e2e-test] client # [ 7.909270] systemd[1]: Stopped Name Service Cache Daemon (nsncd).
  1282. 10.91 s [vm-test-run-centjes-e2e-test] client # [ 7.918326] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
  1283. 10.91 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.052436] dbus-daemon[625]: dbus[625]: Unknown username "systemd-timesync" in message bus configuration file
  1284. 10.93 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.071521] systemd[1]: Reached target Host and Network Name Lookups.
  1285. 10.93 s [vm-test-run-centjes-e2e-test] client # [ 7.937367] systemd-logind[646]: New seat seat0.
  1286. 10.94 s [vm-test-run-centjes-e2e-test] client # [ 7.941779] systemd[1]: Started User Login Management.
  1287. 10.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.084906] systemd[1]: Reached target User and Group Name Lookups.
  1288. 10.95 s [vm-test-run-centjes-e2e-test] client # [ 7.956244] systemd[1]: Starting linger-users.service...
  1289. 10.95 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.099346] systemd[1]: Starting User Login Management...
  1290. 10.96 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.109617] systemd[1]: Found device Virtio network device.
  1291. 10.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.122181] systemd[1]: Finished Firewall.
  1292. 10.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.132632] systemd[1]: Started D-Bus System Message Bus.
  1293. 10.99 s [vm-test-run-centjes-e2e-test] client # [ 7.990624] nsncd[683]: Apr 27 17:53:51.151 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"[ 8.411225] mousedev: PS/2 mouse device common for all mice
  1294. 10.99 s [vm-test-run-centjes-e2e-test] client #
  1295. 11.00 s [vm-test-run-centjes-e2e-test] client # [ 8.011975] systemd[1]: Started Name Service Cache Daemon (nsncd).
  1296. 11.01 s [vm-test-run-centjes-e2e-test] client # [ 8.020564] systemd[1]: Reached target Host and Network Name Lookups.
  1297. 11.02 s [vm-test-run-centjes-e2e-test] client # [ 8.030647] systemd[1]: Reached target User and Group Name Lookups.
  1298. 11.03 s [vm-test-run-centjes-e2e-test] client # [ 8.038466] systemd[1]: linger-users.service: Deactivated successfully.
  1299. 11.03 s [vm-test-run-centjes-e2e-test] client # [ 8.043767] systemd[1]: Finished linger-users.service.
  1300. 11.04 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.190553] systemd-logind[651]: New seat seat0.
  1301. 11.05 s [vm-test-run-centjes-e2e-test] client # [ 8.063531] systemd[1]: Finished resolvconf update.
  1302. 11.06 s [vm-test-run-centjes-e2e-test] client # [ 8.068634] systemd[1]: Reached target Preparation for Network.
  1303. 11.07 s [vm-test-run-centjes-e2e-test] client # [ 8.078598] systemd[1]: Starting DHCP Client...
  1304. 11.07 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.223670] systemd[1]: Started User Login Management.
  1305. 11.08 s [vm-test-run-centjes-e2e-test] client # [ 8.083921] systemd[1]: Starting Address configuration of eth1...
  1306. 11.09 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.238245] systemd[1]: Starting linger-users.service...
  1307. 11.11 s [vm-test-run-centjes-e2e-test] client # [ 8.532210] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
  1308. 11.11 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.249808] systemd[1]: Stopped target Host and Network Name Lookups.
  1309. 11.13 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.269767] systemd[1]: Stopping Host and Network Name Lookups...
  1310. 11.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.285788] systemd[1]: Stopped target User and Group Name Lookups.
  1311. 11.15 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.299652] systemd[1]: Stopping User and Group Name Lookups...
  1312. 11.16 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.309700] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...
  1313. 11.17 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.322251] systemd[1]: nscd.service: Deactivated successfully.
  1314. 11.17 s [vm-test-run-centjes-e2e-test] client # [ 8.599209] ACPI: button: Power Button [PWRF]
  1315. 11.18 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.332990] systemd[1]: Stopped Name Service Cache Daemon (nsncd).
  1316. 11.19 s [vm-test-run-centjes-e2e-test] client # [ 8.198757] network-addresses-eth1-start[713]: adding address 192.168.1.1/24... done
  1317. 11.20 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.344281] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
  1318. 11.20 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.356977] systemd[1]: linger-users.service: Deactivated successfully.
  1319. 11.21 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.363964] systemd[1]: Finished linger-users.service.
  1320. 11.22 s [vm-test-run-centjes-e2e-test] client # [ 8.220479] network-addresses-eth1-start[713]: adding address 2001:db8:1::1/64... done
  1321. 11.23 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.380160] systemd[1]: Started Name Service Cache Daemon (nsncd).
  1322. 11.24 s [vm-test-run-centjes-e2e-test] client # [ 8.668453] rtc_cmos 00:05: RTC can wake from S4
  1323. 11.25 s [vm-test-run-centjes-e2e-test] client # [ 8.258199] systemd[1]: Finished Address configuration of eth1.
  1324. 11.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.388881] nsncd[709]: Apr 27 17:53:51.397 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
  1325. 11.25 s [vm-test-run-centjes-e2e-test] client # [ 8.262419] systemd[1]: Starting Networking Setup...
  1326. 11.25 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.406804] systemd[1]: Reached target Host and Network Name Lookups.
  1327. 11.26 s [vm-test-run-centjes-e2e-test] client # [ 8.686111] Floppy drive(s): fd0 is 2.88M AMI BIOS
  1328. 11.26 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.413648] systemd[1]: Reached target User and Group Name Lookups.
  1329. 11.28 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.842806] mousedev: PS/2 mouse device common for all mice
  1330. 11.28 s [vm-test-run-centjes-e2e-test] client # [ 8.706192] parport_pc 00:03: reported by Plug and Play ACPI
  1331. 11.30 s [vm-test-run-centjes-e2e-test] client # [ 8.308777] dhcpcd[720]: dhcpcd-10.2.4 starting
  1332. 11.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.453568] systemd[1]: Finished resolvconf update.
  1333. 11.30 s [vm-test-run-centjes-e2e-test] client # [ 8.729136] rtc_cmos 00:05: registered as rtc0
  1334. 11.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.463360] systemd[1]: Reached target Preparation for Network.
  1335. 11.31 s [vm-test-run-centjes-e2e-test] client # [ 8.740938] FDC 0 is a S82078B
  1336. 11.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.471799] systemd[1]: Starting DHCP Client...
  1337. 11.32 s [vm-test-run-centjes-e2e-test] client # [ 8.331816] dhcpcd[732]: dev: loaded udev[ 8.747804] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]
  1338. 11.32 s [vm-test-run-centjes-e2e-test] client #
  1339. 11.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.477648] systemd[1]: Starting Address configuration of eth1...
  1340. 11.33 s [vm-test-run-centjes-e2e-test] client # [ 8.756483] rtc_cmos 00:05: setting system clock to 2026-04-27T17:53:51 UTC (1777312431)
  1341. 11.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.897067] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
  1342. 11.35 s [vm-test-run-centjes-e2e-test] client # [ 8.775625] 8021q: 802.1Q VLAN Support v1.8
  1343. 11.35 s [vm-test-run-centjes-e2e-test] client # [ 8.779755] 8021q: adding VLAN 0 to HW filter on device eth1
  1344. 11.37 s [vm-test-run-centjes-e2e-test] client # [ 8.795427] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs
  1345. 11.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.981622] ACPI: button: Power Button [PWRF]
  1346. 11.45 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.598679] network-addresses-eth1-start[738]: adding address 192.168.1.2/24... done
  1347. 11.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.018925] rtc_cmos 00:05: RTC can wake from S4
  1348. 11.47 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.625367] network-addresses-eth1-start[738]: adding address 2001:db8:1::2/64... done
  1349. 11.48 s [vm-test-run-centjes-e2e-test] client # [ 8.906577] cfg80211: Loading compiled-in X.509 certificates for regulatory database
  1350. 11.50 s [vm-test-run-centjes-e2e-test] client # [ 8.920596] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
  1351. 11.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.658522] systemd[1]: Finished Address configuration of eth1.[ 9.070066] Floppy drive(s): fd0 is 2.88M AMI BIOS
  1352. 11.51 s [vm-test-run-centjes-e2e-test] docsserver #
  1353. 11.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.669460] systemd[1]: Starting Networking Setup...
  1354. 11.52 s [vm-test-run-centjes-e2e-test] client # [ 8.533939] systemd[1]: Finished Networking Setup.
  1355. 11.53 s [vm-test-run-centjes-e2e-test] client # [ 8.951429] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
  1356. 11.53 s [vm-test-run-centjes-e2e-test] client # [ 8.542643] systemd[1]: Reached target Network.[ 8.958880] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
  1357. 11.54 s [vm-test-run-centjes-e2e-test] client #
  1358. 11.54 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.104963] parport_pc 00:03: reported by Plug and Play ACPI
  1359. 11.54 s [vm-test-run-centjes-e2e-test] client # [ 8.967166] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
  1360. 11.55 s [vm-test-run-centjes-e2e-test] client # [ 8.972569] cfg80211: failed to load regulatory.db
  1361. 11.55 s [vm-test-run-centjes-e2e-test] client # [ 8.562403] systemd[1]: Starting Permit User Sessions...
  1362. 11.56 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.119858] rtc_cmos 00:05: registered as rtc0
  1363. 11.56 s [vm-test-run-centjes-e2e-test] client # [ 8.989933] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD
  1364. 11.58 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.141636] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]
  1365. 11.58 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.739511] dhcpcd[745]: dhcpcd-10.2.4 starting
  1366. 11.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.151888] FDC 0 is a S82078B
  1367. 11.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.156201] rtc_cmos 00:05: setting system clock to 2026-04-27T17:53:51 UTC (1777312431)
  1368. 11.60 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.161977] systemd-journald[391]: Time jumped backwards, rotating.
  1369. 11.61 s [vm-test-run-centjes-e2e-test] client # [ 9.036178] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input4
  1370. 11.62 s [vm-test-run-centjes-e2e-test] client # [ 8.631361] systemd[1]: Finished Permit User Sessions.
  1371. 11.63 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.195863] 8021q: 802.1Q VLAN Support v1.8
  1372. 11.63 s [vm-test-run-centjes-e2e-test] client # [ 8.644320] systemd[1]: Started Getty on tty1.
  1373. 11.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.201476] 8021q: adding VLAN 0 to HW filter on device eth1
  1374. 11.64 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.204182] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs
  1375. 11.64 s [vm-test-run-centjes-e2e-test] client # [ 8.649752] systemd[1]: Reached target Login Prompts.
  1376. 11.66 s [vm-test-run-centjes-e2e-test] client # [ 9.087478] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console
  1377. 11.67 s [vm-test-run-centjes-e2e-test] client # [ 9.099826] 8021q: adding VLAN 0 to HW filter on device eth0
  1378. 11.68 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.821689] dhcpcd[758]: dev: loaded udev
  1379. 11.68 s [vm-test-run-centjes-e2e-test] client # [ 8.692555] dhcpcd[732]: eth0: waiting for carrier
  1380. 11.69 s [vm-test-run-centjes-e2e-test] client # [ 8.695671] dhcpcd[732]: libudev: received NULL device
  1381. 11.69 s [vm-test-run-centjes-e2e-test] client # [ 8.701655] dhcpcd[732]: libudev: received NULL device
  1382. 11.74 s [vm-test-run-centjes-e2e-test] client # [ 9.144140] cryptd: max_cpu_qlen set to 1000
  1383. 11.75 s [vm-test-run-centjes-e2e-test] client # [ 9.145209] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6
  1384. 11.77 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.332095] cfg80211: Loading compiled-in X.509 certificates for regulatory database
  1385. 11.77 s [vm-test-run-centjes-e2e-test] client # [ 9.145876] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5
  1386. 11.79 s [vm-test-run-centjes-e2e-test] client # [ 9.169396] Console: switching to colour dummy device 80x25
  1387. 11.80 s [vm-test-run-centjes-e2e-test] client # [ 9.172642] AES CTR mode by8 optimization enabled
  1388. 11.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.369378] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
  1389. 11.81 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.373873] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
  1390. 11.81 s [vm-test-run-centjes-e2e-test] client # [ 9.241206] [drm] Found bochs VGA, ID 0xb0c5.
  1391. 11.82 s [vm-test-run-centjes-e2e-test] client # [ 9.242354] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.
  1392. 11.82 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.382287] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
  1393. 11.83 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.388776] cfg80211: failed to load regulatory.db
  1394. 11.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 8.996731] systemd[1]: Finished Networking Setup.
  1395. 11.85 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.007486] systemd[1]: Reached target Network.
  1396. 11.86 s [vm-test-run-centjes-e2e-test] client # [ 8.869929] systemd[1]: Starting Virtual Console Setup...
  1397. 11.86 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.014350] systemd[1]: Starting Permit User Sessions...
  1398. 11.88 s [vm-test-run-centjes-e2e-test] client # [ 9.307491] [drm] Found EDID data blob.
  1399. 11.90 s [vm-test-run-centjes-e2e-test] client # [ 9.325285] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0
  1400. 11.90 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.467461] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
  1401. 11.92 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.071182] systemd[1]: Finished Permit User Sessions.
  1402. 11.92 s [vm-test-run-centjes-e2e-test] client # [ 8.930477] systemd-logind[646]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
  1403. 11.92 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.080205] systemd[1]: Started Getty on tty1.
  1404. 11.93 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.086782] systemd[1]: Reached target Login Prompts.
  1405. 11.94 s [vm-test-run-centjes-e2e-test] client # [ 8.944407] systemd-logind[646]: Watching system buttons on /dev/input/event2 (Power Button)
  1406. 11.96 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.522775] 8021q: adding VLAN 0 to HW filter on device eth0
  1407. 11.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.528766] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input4
  1408. 11.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.126881] dhcpcd[758]: eth0: waiting for carrier
  1409. 11.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.538212] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD
  1410. 11.99 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.551540] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console
  1411. 12.04 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.577919] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6
  1412. 12.05 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.578455] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5
  1413. 12.06 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.583765] cryptd: max_cpu_qlen set to 1000
  1414. 12.07 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.600087] Console: switching to colour dummy device 80x25
  1415. 12.08 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.602854] AES CTR mode by8 optimization enabled
  1416. 12.09 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.657401] [drm] Found bochs VGA, ID 0xb0c5.
  1417. 12.09 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.658601] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.
  1418. 12.14 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.296795] systemd[1]: Starting Virtual Console Setup...
  1419. 12.15 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.713782] [drm] Found EDID data blob.
  1420. 12.16 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.726854] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0
  1421. 12.54 s [vm-test-run-centjes-e2e-test] client # [ 9.551828] dhcpcd[732]: eth0: carrier acquired
  1422. 12.55 s [vm-test-run-centjes-e2e-test] client # [ 9.565899] dhcpcd[732]: DUID 00:01:00:01:31:82:5f:30:52:54:00:12:34:56
  1423. 12.56 s [vm-test-run-centjes-e2e-test] client # [ 9.568721] dhcpcd[732]: eth0: IAID 00:12:34:56
  1424. 12.56 s [vm-test-run-centjes-e2e-test] client # [ 9.570850] dhcpcd[732]: eth0: adding address fe80::5054:ff:fe12:3456
  1425. 12.70 s [vm-test-run-centjes-e2e-test] client # [ 9.365434] fbcon: bochs-drmdrmfb (fb0) is primary device
  1426. 12.70 s [vm-test-run-centjes-e2e-test] client # [ 9.993278] Console: switching to colour frame buffer device 160x50
  1427. 12.70 s [vm-test-run-centjes-e2e-test] client # [ 10.130358] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device
  1428. 12.80 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.956639] dhcpcd[758]: eth0: carrier acquired
  1429. 12.91 s [vm-test-run-centjes-e2e-test] client # [ 10.341294] ppdev: user-space parallel port driver
  1430. 12.94 s [vm-test-run-centjes-e2e-test] client # [ 10.368388] kvm_amd: TSC scaling supported
  1431. 12.94 s [vm-test-run-centjes-e2e-test] client # [ 10.369473] kvm_amd: Nested Virtualization enabled
  1432. 12.94 s [vm-test-run-centjes-e2e-test] client # [ 10.370752] kvm_amd: Nested Paging enabled
  1433. 12.94 s [vm-test-run-centjes-e2e-test] client # [ 10.371818] kvm_amd: LBR virtualization supported
  1434. 12.96 s [vm-test-run-centjes-e2e-test] client # [ 9.967349] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
  1435. 12.97 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.750891] fbcon: bochs-drmdrmfb (fb0) is primary device
  1436. 12.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.391589] Console: switching to colour frame buffer device 160x50
  1437. 12.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.544632] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device
  1438. 12.98 s [vm-test-run-centjes-e2e-test] client # [ 10.408487] kvm_amd: Virtual VMLOAD VMSAVE supported
  1439. 12.98 s [vm-test-run-centjes-e2e-test] client # [ 10.409710] kvm_amd: Virtual GIF supported
  1440. 12.98 s [vm-test-run-centjes-e2e-test] docsserver # [ 9.977680] dhcpcd[758]: DUID 00:01:00:01:31:82:5f:30:52:54:00:12:34:56
  1441. 12.99 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.144156] dhcpcd[758]: eth0: IAID 00:12:34:56
  1442. 12.99 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.146325] systemd-logind[651]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
  1443. 13.00 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.156883] dhcpcd[758]: eth0: adding address fe80::5054:ff:fe12:3456
  1444. 13.05 s [vm-test-run-centjes-e2e-test] client # [ 10.060316] systemd-logind[646]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)
  1445. 13.11 s [vm-test-run-centjes-e2e-test] client # [ 10.112194] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
  1446. 13.11 s [vm-test-run-centjes-e2e-test] client # [ 10.120765] systemd[1]: Stopped Virtual Console Setup.
  1447. 13.12 s [vm-test-run-centjes-e2e-test] client # [ 10.128762] systemd[1]: Starting Virtual Console Setup...
  1448. 13.14 s [vm-test-run-centjes-e2e-test] client # [ 10.565809] EDAC MC: Ver: 3.0.0
  1449. 13.17 s [vm-test-run-centjes-e2e-test] client # [ 10.175475] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
  1450. 13.18 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.742336] kvm_amd: TSC scaling supported
  1451. 13.18 s [vm-test-run-centjes-e2e-test] client # [ 10.188883] systemd[1]: Stopped Virtual Console Setup.
  1452. 13.18 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.743408] kvm_amd: Nested Virtualization enabled
  1453. 13.18 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.744612] kvm_amd: Nested Paging enabled
  1454. 13.18 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.745640] kvm_amd: LBR virtualization supported
  1455. 13.18 s [vm-test-run-centjes-e2e-test] client # [ 10.193644] systemd[1]: Starting Virtual Console Setup...
  1456. 13.19 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.758465] kvm_amd: Virtual VMLOAD VMSAVE supported
  1457. 13.20 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.759756] kvm_amd: Virtual GIF supported
  1458. 13.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.427732] systemd-logind[651]: Watching system buttons on /dev/input/event2 (Power Button)
  1459. 13.30 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.864880] ppdev: user-space parallel port driver
  1460. 13.31 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.873476] EDAC MC: Ver: 3.0.0
  1461. 13.33 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.484625] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
  1462. 13.42 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.568797] systemd-logind[651]: Watching system buttons on /dev/input/event5 (QEMU Virtio Keyboard)
  1463. 13.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.606180] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
  1464. 13.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.614831] systemd[1]: Stopped Virtual Console Setup.
  1465. 13.47 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.623620] systemd[1]: Starting Virtual Console Setup...
  1466. 13.50 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.658955] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
  1467. 13.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.663554] systemd[1]: Stopped Virtual Console Setup.
  1468. 13.51 s [vm-test-run-centjes-e2e-test] docsserver # [ 10.670814] systemd[1]: Starting Virtual Console Setup...
  1469. 13.63 s [vm-test-run-centjes-e2e-test] client # [ 10.636830] systemd[1]: Finished Virtual Console Setup.
  1470. 13.94 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.092714] systemd[1]: Finished Virtual Console Setup.
  1471. 14.32 s [vm-test-run-centjes-e2e-test] client # [ 11.332689] dhcpcd[732]: eth0: soliciting a DHCP lease
  1472. 14.32 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.477586] dhcpcd[758]: eth0: soliciting a DHCP lease
  1473. 14.34 s [vm-test-run-centjes-e2e-test] client # [ 11.766944] NET: Registered PF_PACKET protocol family
  1474. 14.34 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.905788] NET: Registered PF_PACKET protocol family
  1475. 14.35 s [vm-test-run-centjes-e2e-test] client # [ 11.362860] dhcpcd[732]: eth0: offered 10.0.2.15 from 10.0.2.2
  1476. 14.35 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.508938] dhcpcd[758]: eth0: offered 10.0.2.15 from 10.0.2.2
  1477. 14.35 s [vm-test-run-centjes-e2e-test] client # [ 11.366378] dhcpcd[732]: eth0: probing address 10.0.2.15/24
  1478. 14.36 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.512372] dhcpcd[758]: eth0: probing address 10.0.2.15/24
  1479. 14.48 s [vm-test-run-centjes-e2e-test] client # [ 11.490386] dhcpcd[732]: eth0: soliciting an IPv6 router
  1480. 14.48 s [vm-test-run-centjes-e2e-test] client # [ 11.492945] dhcpcd[732]: eth0: Router Advertisement from fe80::2
  1481. 14.48 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.639227] dhcpcd[758]: eth0: soliciting an IPv6 router
  1482. 14.48 s [vm-test-run-centjes-e2e-test] client # [ 11.495582] dhcpcd[732]: eth0: adding address fec0::5054:ff:fe12:3456/64
  1483. 14.48 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.641902] dhcpcd[758]: eth0: Router Advertisement from fe80::2
  1484. 14.48 s [vm-test-run-centjes-e2e-test] client # [ 11.497868] dhcpcd[732]: eth0: adding route to fec0::/64
  1485. 14.49 s [vm-test-run-centjes-e2e-test] client # [ 11.499687] dhcpcd[732]: eth0: adding default route via fe80::2
  1486. 14.49 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.643944] dhcpcd[758]: eth0: adding address fec0::5054:ff:fe12:3456/64
  1487. 14.49 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.646619] dhcpcd[758]: eth0: adding route to fec0::/64
  1488. 14.49 s [vm-test-run-centjes-e2e-test] docsserver # [ 11.648516] dhcpcd[758]: eth0: adding default route via fe80::2
  1489. 19.11 s [vm-test-run-centjes-e2e-test] client # [ 16.120992] dhcpcd[732]: eth0: leased 10.0.2.15 for 86400 seconds
  1490. 19.11 s [vm-test-run-centjes-e2e-test] client # [ 16.124372] dhcpcd[732]: eth0: adding route to 10.0.2.0/24
  1491. 19.19 s [vm-test-run-centjes-e2e-test] client # [ 16.127258] dhcpcd[732]: eth0: adding default route via 10.0.2.2
  1492. 19.25 s [vm-test-run-centjes-e2e-test] client # [ 16.258499] systemd[1]: Started DHCP Client.
  1493. 19.25 s [vm-test-run-centjes-e2e-test] client # [ 16.265227] systemd[1]: Reached target Multi-User System.
  1494. 19.26 s [vm-test-run-centjes-e2e-test] client # [ 16.267712] systemd[1]: Startup finished in 3.686s (kernel) + 12.576s (userspace) = 16.262s.
  1495. 19.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 16.614726] dhcpcd[758]: eth0: leased 10.0.2.15 for 86400 seconds
  1496. 19.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 16.617549] dhcpcd[758]: eth0: adding route to 10.0.2.0/24
  1497. 19.46 s [vm-test-run-centjes-e2e-test] docsserver # [ 16.619711] dhcpcd[758]: eth0: adding default route via 10.0.2.2
  1498. 19.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 16.739296] systemd[1]: Started DHCP Client.
  1499. 19.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 16.744871] systemd[1]: Reached target Multi-User System.
  1500. 19.59 s [vm-test-run-centjes-e2e-test] docsserver # [ 16.747182] systemd[1]: Startup finished in 3.827s (kernel) + 12.915s (userspace) = 16.743s.
  1501. 19.73 s [vm-test-run-centjes-e2e-test] docsserver: (finished: waiting for unit default.target, in 17.96 seconds)
  1502. 19.73 s [vm-test-run-centjes-e2e-test] client: waiting for unit default.target
  1503. 19.73 s [vm-test-run-centjes-e2e-test] client: waiting for the VM to finish booting
  1504. 19.73 s [vm-test-run-centjes-e2e-test] client: Guest shell says: b'Spawning backdoor root shell...\n'
  1505. 19.73 s [vm-test-run-centjes-e2e-test] client: connected to guest root shell
  1506. 19.73 s [vm-test-run-centjes-e2e-test] client: (connecting took 0.00 seconds)
  1507. 19.73 s [vm-test-run-centjes-e2e-test] client: (finished: waiting for the VM to finish booting, in 0.00 seconds)
  1508. 19.86 s [vm-test-run-centjes-e2e-test] client: (finished: waiting for unit default.target, in 0.13 seconds)
  1509. 19.94 s [vm-test-run-centjes-e2e-test] docsserver: waiting for unit centjes-docs-site-production.service
  1510. 20.00 s [vm-test-run-centjes-e2e-test] docsserver: (finished: waiting for unit centjes-docs-site-production.service, in 0.06 seconds)
  1511. 20.00 s [vm-test-run-centjes-e2e-test] docsserver: waiting for unit default.target
  1512. 20.05 s [vm-test-run-centjes-e2e-test] docsserver: (finished: waiting for unit default.target, in 0.05 seconds)
  1513. 20.05 s [vm-test-run-centjes-e2e-test] docsserver: waiting for TCP port 8001 on localhost
  1514. 20.13 s [vm-test-run-centjes-e2e-test] docsserver # Connection to localhost (127.0.0.1) 8001 port [tcp/vcom-tunnel] succeeded!
  1515. 20.13 s [vm-test-run-centjes-e2e-test] docsserver: (finished: waiting for TCP port 8001 on localhost, in 0.08 seconds)
  1516. 20.13 s [vm-test-run-centjes-e2e-test] client: must succeed: curl docsserver:8001
  1517. 20.23 s [vm-test-run-centjes-e2e-test] client # % Total % Received % Xferd Average Speed Time Time Time Current
  1518. 20.25 s [vm-test-run-centjes-e2e-test] client # Dload Upload Total Spent Left Speed
  1519. 20.27 s [vm-test-run-centjes-e2e-test] docsserver # [ 17.427838] centjes-docs-site-production-start[637]: 192.168.1.1 - - [27/Apr/2026:17:54:00 +0000] "GET / HTTP/1.1" 200 3595 "" "curl/8.17.0"
  1520. 20.29 s [vm-test-run-centjes-e2e-test] client # 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0100 3595 100 3595 0 0 59057 0 --:--:-- --:--:-- --:--:-- 59916
  1521. 20.29 s [vm-test-run-centjes-e2e-test] client: (finished: must succeed: curl docsserver:8001, in 0.16 seconds)
  1522. 20.29 s [vm-test-run-centjes-e2e-test] (finished: run the VM test script, in 18.80 seconds)
  1523. 20.44 s [vm-test-run-centjes-e2e-test] test script finished in 18.94s
  1524. 20.44 s [vm-test-run-centjes-e2e-test] cleanup
  1525. 20.44 s [vm-test-run-centjes-e2e-test] kill machine (pid 31)
  1526. 20.44 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)
  1527. 20.45 s [vm-test-run-centjes-e2e-test] kill machine (pid 9)
  1528. 20.45 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)
  1529. 20.46 s [vm-test-run-centjes-e2e-test] kill vlan (pid 7)
  1530. 20.46 s [vm-test-run-centjes-e2e-test] (finished: cleanup, in 0.02 seconds)
  1531. 20.57 s [post-build-hook] Uploading paths from nix-ci to cachix cache "centjes" /nix/store/2q5nz7v6sb5c0fa9raw2bsgfa9jp0278-vm-test-run-centjes-e2e-test
  1532. 20.97 s [post-build-hook] Pushing 1 paths (0 are already present) using zstd to cache centjes ⏳
  1533. 20.97 s [post-build-hook]
  1534. 21.37 s [post-build-hook] Pushing /nix/store/2q5nz7v6sb5c0fa9raw2bsgfa9jp0278-vm-test-run-centjes-e2e-test (96.00 B)
  1535. 22.92 s [post-build-hook]
  1536. 22.92 s [post-build-hook] All done.
  1537. 22.93 s [post-build-hook] Uploading paths from nix-ci to the NixCI staging cache /nix/store/2q5nz7v6sb5c0fa9raw2bsgfa9jp0278-vm-test-run-centjes-e2e-test
  1538. 22.97 s [post-build-hook] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
  1539. 22.98 s [post-build-hook] copying 1 paths...
  1540. 22.98 s [post-build-hook] copying path '/nix/store/2q5nz7v6sb5c0fa9raw2bsgfa9jp0278-vm-test-run-centjes-e2e-test' to 'https://cache.staging.nix-ci.com'...
  1541. 23.08 s [post-build-hook] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
  1542. 23.33 s [post-build-hook] copying 0 paths...
  1543. 23.35 s Progress: 1 of 2 built, 10 of 10 fetched
More Logs Raw Logs

Agent Instructions

Wait for completion, without logs:

curl --netrc -N https://staging.nix-ci.com/gh:NorfairKing:centjes/fix-price-change-not-counted-in-average/ac5d1a5f099cac7cf5a8619e4f02fa69a4308bdd/4cb19690-0e80-4215-b818-500534709603/wait

Latest logs, with timestamps (streams while running):

curl --netrc -N https://staging.nix-ci.com/gh:NorfairKing:centjes/fix-price-change-not-counted-in-average/ac5d1a5f099cac7cf5a8619e4f02fa69a4308bdd/4cb19690-0e80-4215-b818-500534709603/tail

All logs, with timestamps (available after completion):

curl --netrc https://staging.nix-ci.com/gh:NorfairKing:centjes/fix-price-change-not-counted-in-average/ac5d1a5f099cac7cf5a8619e4f02fa69a4308bdd/4cb19690-0e80-4215-b818-500534709603/logs

Raw logs, from the start (no timestamps):

curl --netrc -N https://staging.nix-ci.com/gh:NorfairKing:centjes/fix-price-change-not-counted-in-average/ac5d1a5f099cac7cf5a8619e4f02fa69a4308bdd/4cb19690-0e80-4215-b818-500534709603/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/fix-price-change-not-counted-in-average/ac5d1a5f099cac7cf5a8619e4f02fa69a4308bdd/4cb19690-0e80-4215-b818-500534709603/logs

Reproduce locally: https://staging.nix-ci.com/gh:NorfairKing:centjes/fix-price-change-not-counted-in-average/ac5d1a5f099cac7cf5a8619e4f02fa69a4308bdd/4cb19690-0e80-4215-b818-500534709603/repro

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