vm-test-run-wifi
checks.x86_64-linux.wifi
· build #200
· raw
1build-x86-01: building gjqnkh5maxivqk5ln2pg1badzskk1825-vm-test-run-wifi.drv2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7additionally exposed symbols:8 second, test,9 vlan1,10 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh11start all VMs12second: starting vm13test: starting vm14test # Disk image does not exist, creating the virtualisation disk image...15test # Formatting '/build/vm-state-test/tmp.UC1MD2vdRA', fmt=raw size=107374182416test: QEMU running (pid 48)17test # mke2fs 1.47.4 (6-Mar-2025)18test # Discarding device blocks: 0/262144 done19test # Creating filesystem with 262144 4k blocks and 65536 inodes20test # Filesystem UUID: 3660870a-a02f-4bcd-a02b-d39094ca8a2421test # Superblock backups stored on blocks:22test # 32768, 98304, 163840, 22937623test # 24test # Allocating group tables: 0/8 done25test # Writing inode tables: 0/8 done26test # Creating journal (8192 blocks): done27test # Writing superblocks and filesystem accounting information: 0/8 done28test # 29test # Virtualisation disk image created.30test # Starting virtiofs daemons...31test # [2026-09-30T15:03:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)32test # [2026-09-30T15:03:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether33test # [2026-09-30T15:03:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)34test # [2026-09-30T15:03:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether35test # [2026-09-30T15:03:47Z INFO virtiofsd] Waiting for vhost-user socket connection...36test # [2026-09-30T15:03:47Z INFO virtiofsd] Waiting for vhost-user socket connection...37test # [2026-09-30T15:03:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)38test # [2026-09-30T15:03:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether39test # [2026-09-30T15:03:47Z INFO virtiofsd] Waiting for vhost-user socket connection...40test # [2026-09-30T15:03:47Z INFO virtiofsd] Client connected, servicing requests41test # [2026-09-30T15:03:47Z INFO virtiofsd] Client connected, servicing requests42test # [2026-09-30T15:03:47Z INFO virtiofsd] Client connected, servicing requests43second # Disk image does not exist, creating the virtualisation disk image...44second # Formatting '/build/vm-state-second/tmp.N1bWsSexuG', fmt=raw size=107374182445second # mke2fs 1.47.4 (6-Mar-2025)46second # Discarding device blocks: 0/262144 done47second # Creating filesystem with 262144 4k blocks and 65536 inodes48second # Filesystem UUID: c473103b-a00f-42f3-b58e-67248016957749second # Superblock backups stored on blocks:50second # 32768, 98304, 163840, 22937651second # 52second # Allocating group tables: 0/8 done53second # Writing inode tables: 0/8 done54second # Creating journal (8192 blocks): done55second # Writing superblocks and filesystem accounting information: 0/8 done56second # 57second: QEMU running (pid 47)58second # Virtualisation disk image created.59second # Starting virtiofs daemons...60second # [2026-09-30T15:03:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)61second # [2026-09-30T15:03:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether62second # [2026-09-30T15:03:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)63second # [2026-09-30T15:03:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether64second # [2026-09-30T15:03:47Z INFO virtiofsd] Waiting for vhost-user socket connection...65second # [2026-09-30T15:03:47Z INFO virtiofsd] Waiting for vhost-user socket connection...66second # [2026-09-30T15:03:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)67second # [2026-09-30T15:03:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether68second # [2026-09-30T15:03:47Z INFO virtiofsd] Waiting for vhost-user socket connection...69(finished: start all VMs, in 0.10 seconds)70test: waiting for unit NetworkManager.service71second # [2026-09-30T15:03:47Z INFO virtiofsd] Client connected, servicing requests72test: waiting for the VM to finish booting73second # [2026-09-30T15:03:47Z INFO virtiofsd] Client connected, servicing requests74second # [2026-09-30T15:03:47Z INFO virtiofsd] Client connected, servicing requests75test # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)76second # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)77test # 78test # 79test # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0080second # 81second # 82second # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0083test # Press Ctrl-B to configure iPXE (PCI 00:02.0)...84test # 85test # 86test # 87test # 88test # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0089second # Press Ctrl-B to configure iPXE (PCI 00:02.0)...90second # 91second # 92second # 93second # 94second # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0095test # Press Ctrl-B to configure iPXE (PCI 00:05.0)...96test # 97test # 98second # Press Ctrl-B to configure iPXE (PCI 00:05.0)...99second # 100second # 101test # Booting from ROM...102second # Booting from ROM...103test # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 25 14:35:54 UTC 2026104second # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 25 14:35:54 UTC 2026105test # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/2dnj4v4pbn4gkq66j0l85n4jkfwcnxaw-nixos-system-test-test/init regInfo=/nix/.ro-store/7p8fmi274bpix5b7d67cmlkg1xb4yfpr-closure-info/registration console=ttyS0,115200n8 console=tty0106test # [ 0.000000] BIOS-provided physical RAM map:107second # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/l8f9z309rr1m6j8y480gr50fzkvrzwkk-nixos-system-second-test/init regInfo=/nix/.ro-store/6gx82v2qjrbqivw7mvrn4wiq88xl5452-closure-info/registration console=ttyS0,115200n8 console=tty0108second # [ 0.000000] BIOS-provided physical RAM map:109test # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable110second # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable111test # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved112second # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved113test # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved114second # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved115test # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable116test # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved117second # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable118test # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved119second # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved120test # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved121second # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved122test # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved123second # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved124test # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved125second # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved126test # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved127second # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved128test # [ 0.000000] NX (Execute Disable) protection: active129test # [ 0.000000] APIC: Static calls initialized130second # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved131test # [ 0.000000] SMBIOS 2.8 present.132second # [ 0.000000] NX (Execute Disable) protection: active133second # [ 0.000000] APIC: Static calls initialized134test # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014135second # [ 0.000000] SMBIOS 2.8 present.136test # [ 0.000000] DMI: Memory slots populated: 1/1137test # [ 0.000000] Hypervisor detected: KVM138second # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014139test # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000140second # [ 0.000000] DMI: Memory slots populated: 1/1141test # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00142second # [ 0.000000] Hypervisor detected: KVM143test # [ 0.000000] kvm-clock: using sched offset of 402913783 cycles144second # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000145second # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00146test # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns147second # [ 0.000000] kvm-clock: using sched offset of 402680283 cycles148test # [ 0.000005] tsc: Detected 4192.120 MHz processor149test # [ 0.000561] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000150second # [ 0.000001] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns151second # [ 0.000004] tsc: Detected 4192.120 MHz processor152test # [ 0.000589] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs153second # [ 0.000569] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000154test # [ 0.000592] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT155second # [ 0.000598] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs156test # [ 0.002244] found SMP MP-table at [mem 0x000f5450-0x000f545f]157test # [ 0.002257] Using GB pages for direct mapping158second # [ 0.000600] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT159test # [ 0.002341] RAMDISK: [mem 0x3e24f000-0x3ffcffff]160second # [ 0.002253] found SMP MP-table at [mem 0x000f5450-0x000f545f]161test # [ 0.002345] ACPI: Early table checksum verification disabled162second # [ 0.002265] Using GB pages for direct mapping163test # [ 0.002348] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )164second # [ 0.002339] RAMDISK: [mem 0x3e250000-0x3ffcffff]165second # [ 0.002343] ACPI: Early table checksum verification disabled166test # [ 0.002353] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)167second # [ 0.002346] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )168test # [ 0.002358] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)169second # [ 0.002348] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)170test # [ 0.002363] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)171second # [ 0.002352] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)172test # [ 0.002365] ACPI: FACS 0x000000003FFE0000 000040173second # [ 0.002356] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)174test # [ 0.002366] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)175second # [ 0.002358] ACPI: FACS 0x000000003FFE0000 000040176test # [ 0.002367] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)177second # [ 0.002359] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)178test # [ 0.002368] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)179second # [ 0.002360] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)180test # [ 0.002369] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)181second # [ 0.002361] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)182test # [ 0.002370] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]183second # [ 0.002362] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)184test # [ 0.002371] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]185second # [ 0.002363] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]186test # [ 0.002371] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]187second # [ 0.002364] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]188test # [ 0.002372] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]189test # [ 0.002372] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]190second # [ 0.002365] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]191test # [ 0.002372] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]192second # [ 0.002365] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]193test # [ 0.002373] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]194second # [ 0.002365] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]195test # [ 0.002718] No NUMA configuration found196second # [ 0.002366] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]197test # [ 0.002720] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]198test # [ 0.002722] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]199second # [ 0.002366] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]200test # [ 0.002787] Zone ranges:201second # [ 0.002713] No NUMA configuration found202test # [ 0.002788] DMA [mem 0x0000000000001000-0x0000000000ffffff]203second # [ 0.002715] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]204test # [ 0.002789] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]205test # [ 0.002790] Normal empty206second # [ 0.002717] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]207test # [ 0.002790] Device empty208second # [ 0.002783] Zone ranges:209test # [ 0.002791] Movable zone start for each node210test # [ 0.002791] Early memory node ranges211second # [ 0.002784] DMA [mem 0x0000000000001000-0x0000000000ffffff]212test # [ 0.002792] node 0: [mem 0x0000000000001000-0x000000000009efff]213second # [ 0.002785] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]214second # [ 0.002786] Normal empty215second # [ 0.002786] Device empty216test # [ 0.002793] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]217second # [ 0.002787] Movable zone start for each node218test # [ 0.002793] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]219second # [ 0.002787] Early memory node ranges220test # [ 0.002811] On node 0, zone DMA: 1 pages in unavailable ranges221second # [ 0.002787] node 0: [mem 0x0000000000001000-0x000000000009efff]222test # [ 0.003007] On node 0, zone DMA: 97 pages in unavailable ranges223second # [ 0.002788] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]224test # [ 0.015802] On node 0, zone DMA32: 40 pages in unavailable ranges225test # [ 0.016588] ACPI: PM-Timer IO Port: 0x608226second # [ 0.002789] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]227test # [ 0.016597] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])228second # [ 0.002804] On node 0, zone DMA: 1 pages in unavailable ranges229test # [ 0.016617] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23230second # [ 0.002995] On node 0, zone DMA: 97 pages in unavailable ranges231test # [ 0.016619] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)232second # [ 0.015783] On node 0, zone DMA32: 40 pages in unavailable ranges233second # [ 0.016568] ACPI: PM-Timer IO Port: 0x608234test # [ 0.016620] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)235second # [ 0.016577] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])236test # [ 0.016621] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)237test # [ 0.016622] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)238second # [ 0.016596] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23239test # [ 0.016623] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)240second # [ 0.016598] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)241test # [ 0.016624] ACPI: Using ACPI (MADT) for SMP configuration information242second # [ 0.016600] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)243test # [ 0.016625] ACPI: HPET id: 0x8086a201 base: 0xfed00000244second # [ 0.016600] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)245test # [ 0.016628] TSC deadline timer available246test # [ 0.016632] CPU topo: Max. logical packages: 1247second # [ 0.016601] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)248test # [ 0.016632] CPU topo: Max. logical dies: 1249test # [ 0.016633] CPU topo: Max. dies per package: 1250second # [ 0.016602] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)251test # [ 0.016635] CPU topo: Max. threads per core: 1252second # [ 0.016603] ACPI: Using ACPI (MADT) for SMP configuration information253test # [ 0.016636] CPU topo: Num. cores per package: 1254second # [ 0.016604] ACPI: HPET id: 0x8086a201 base: 0xfed00000255test # [ 0.016636] CPU topo: Num. threads per package: 1256second # [ 0.016606] TSC deadline timer available257test # [ 0.016636] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs258second # [ 0.016610] CPU topo: Max. logical packages: 1259test # [ 0.016649] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()260second # [ 0.016610] CPU topo: Max. logical dies: 1261second # [ 0.016611] CPU topo: Max. dies per package: 1262test # [ 0.016674] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]263second # [ 0.016613] CPU topo: Max. threads per core: 1264test # [ 0.016675] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]265second # [ 0.016614] CPU topo: Num. cores per package: 1266test # [ 0.016676] [mem 0x40000000-0xafffffff] available for PCI devices267second # [ 0.016614] CPU topo: Num. threads per package: 1268test # [ 0.016678] Booting paravirtualized kernel on KVM269second # [ 0.016614] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs270test # [ 0.016680] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns271second # [ 0.016627] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()272test # [ 0.020072] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1273second # [ 0.016651] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]274test # [ 0.021798] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152275second # [ 0.016653] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]276test # [ 0.021830] kvm-guest: PV spinlocks disabled, single CPU277second # [ 0.016654] [mem 0x40000000-0xafffffff] available for PCI devices278second # [ 0.016654] Booting paravirtualized kernel on KVM279second # [ 0.016658] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns280second # [ 0.020051] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1281test # [ 0.021832] Kernel command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/2dnj4v4pbn4gkq66j0l85n4jkfwcnxaw-nixos-system-test-test/init regInfo=/nix/.ro-store/7p8fmi274bpix5b7d67cmlkg1xb4yfpr-closure-info/registration console=ttyS0,115200n8 console=tty0282second # [ 0.021787] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152283second # [ 0.021817] kvm-guest: PV spinlocks disabled, single CPU284test # [ 0.021906] Unknown kernel command line parameters "regInfo=/nix/.ro-store/7p8fmi274bpix5b7d67cmlkg1xb4yfpr-closure-info/registration", will be passed to user space.285test # [ 0.022100] random: crng init done286test # [ 0.022101] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes287test # [ 0.022117] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)288second # [ 0.021819] Kernel command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/l8f9z309rr1m6j8y480gr50fzkvrzwkk-nixos-system-second-test/init regInfo=/nix/.ro-store/6gx82v2qjrbqivw7mvrn4wiq88xl5452-closure-info/registration console=ttyS0,115200n8 console=tty0289test # [ 0.022129] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)290test # [ 0.022155] Fallback order for Node 0: 0291test # [ 0.022158] Built 1 zonelists, mobility grouping on. Total pages: 262006292test # [ 0.022159] Policy zone: DMA32293second # [ 0.021890] Unknown kernel command line parameters "regInfo=/nix/.ro-store/6gx82v2qjrbqivw7mvrn4wiq88xl5452-closure-info/registration", will be passed to user space.294second # [ 0.022084] random: crng init done295test # [ 0.023941] mem auto-init: stack:all(zero), heap alloc:on, heap free:off296test # [ 0.026309] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1297second # [ 0.022084] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes298test # [ 0.027934] allocated 2097152 bytes of page_ext299second # [ 0.022103] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)300test # [ 0.036981] ftrace: allocating 48820 entries in 192 pages301test # [ 0.036983] ftrace: allocated 192 pages with 2 groups302second # [ 0.022112] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)303test # [ 0.037639] Dynamic Preempt: lazy304second # [ 0.022140] Fallback order for Node 0: 0305test # [ 0.037741] rcu: Preemptible hierarchical RCU implementation.306second # [ 0.022142] Built 1 zonelists, mobility grouping on. Total pages: 262006307test # [ 0.037741] rcu: RCU event tracing is enabled.308second # [ 0.022144] Policy zone: DMA32309test # [ 0.037742] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.310second # [ 0.023967] mem auto-init: stack:all(zero), heap alloc:on, heap free:off311test # [ 0.037743] Trampoline variant of Tasks RCU enabled.312test # [ 0.037743] Rude variant of Tasks RCU enabled.313second # [ 0.026409] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1314test # [ 0.037743] Tracing variant of Tasks RCU enabled.315second # [ 0.028029] allocated 2097152 bytes of page_ext316second # [ 0.037056] ftrace: allocating 48820 entries in 192 pages317test # [ 0.037744] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.318second # [ 0.037057] ftrace: allocated 192 pages with 2 groups319test # [ 0.037745] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1320second # [ 0.037746] Dynamic Preempt: lazy321second # [ 0.037875] rcu: Preemptible hierarchical RCU implementation.322test # [ 0.037760] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.323second # [ 0.037875] rcu: RCU event tracing is enabled.324test # [ 0.037761] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.325second # [ 0.037876] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.326second # [ 0.037877] Trampoline variant of Tasks RCU enabled.327test # [ 0.037762] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.328second # [ 0.037877] Rude variant of Tasks RCU enabled.329test # [ 0.040944] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16330second # [ 0.037878] Tracing variant of Tasks RCU enabled.331test # [ 0.041211] rcu: srcu_init: Setting srcu_struct sizes based on contention.332second # [ 0.037878] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.333test # [ 0.041218] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns334second # [ 0.037879] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1335test # [ 0.041374] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)336second # [ 0.037893] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.337test # [ 0.046642] Console: colour VGA+ 80x25338test # [ 0.046645] printk: legacy console [tty0] enabled339second # [ 0.037894] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.340test # [ 0.076588] printk: legacy console [ttyS0] enabled341test # [ 0.208250] ACPI: Core revision 20250807342second # [ 0.037895] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.343second # [ 0.041062] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16344test # [ 0.209291] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns345second # [ 0.041311] rcu: srcu_init: Setting srcu_struct sizes based on contention.346test # [ 0.211230] APIC: Switch to symmetric I/O mode setup347test # [ 0.212398] x2apic enabled348second # [ 0.041315] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns349test # [ 0.213215] APIC: Switched APIC routing to: physical x2apic350second # [ 0.041477] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)351second # [ 0.046601] Console: colour VGA+ 80x25352second # [ 0.046603] printk: legacy console [tty0] enabled353test # [ 0.215167] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1354second # [ 0.076746] printk: legacy console [ttyS0] enabled355second # [ 0.216327] ACPI: Core revision 20250807356test # [ 0.216398] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3c6d4d0b6d3, max_idle_ns: 440795255641 ns357second # [ 0.217402] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns358test # [ 0.218451] Calibrating delay loop (skipped) preset value.. 8384.24 BogoMIPS (lpj=4192120)359second # [ 0.219364] APIC: Switch to symmetric I/O mode setup360test # [ 0.219535] x86/cpu: User Mode Instruction Prevention (UMIP) activated361second # [ 0.220571] x2apic enabled362test # [ 0.221573] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127363second # [ 0.221410] APIC: Switched APIC routing to: physical x2apic364test # [ 0.222449] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0365second # [ 0.223402] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1366test # [ 0.223452] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto367second # [ 0.224654] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3c6d4d0b6d3, max_idle_ns: 440795255641 ns368test # [ 0.225449] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl369test # [ 0.227449] Spectre V2 : Mitigation: Enhanced / Automatic IBRS370second # [ 0.226759] Calibrating delay loop (skipped) preset value.. 8384.24 BogoMIPS (lpj=4192120)371test # [ 0.228449] Speculative Return Stack Overflow: Mitigation: Safe RET372second # [ 0.227843] x86/cpu: User Mode Instruction Prevention (UMIP) activated373test # [ 0.230449] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization374second # [ 0.228869] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127375second # [ 0.229757] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0376second # [ 0.231760] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto377test # [ 0.231455] Spectre V2 : Enabling IBPB for BPF378second # [ 0.232757] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl379test # [ 0.233450] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier380second # [ 0.233757] Spectre V2 : Mitigation: Enhanced / Automatic IBRS381test # [ 0.234450] active return thunk: srso_alias_return_thunk382second # [ 0.234757] Speculative Return Stack Overflow: Mitigation: Safe RET383test # [ 0.235469] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'384second # [ 0.235757] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization385test # [ 0.236449] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'386test # [ 0.237449] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'387test # [ 0.239449] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'388test # [ 0.240449] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'389second # [ 0.236763] Spectre V2 : Enabling IBPB for BPF390test # [ 0.241449] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'391second # [ 0.238494] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier392test # [ 0.242449] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'393second # [ 0.239759] active return thunk: srso_alias_return_thunk394test # [ 0.244449] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'395second # [ 0.240785] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'396second # [ 0.242758] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'397test # [ 0.245449] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'398second # [ 0.243757] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'399test # [ 0.246450] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256400second # [ 0.244758] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'401test # [ 0.248449] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64402second # [ 0.246757] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'403test # [ 0.249449] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512404test # [ 0.250449] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024405second # [ 0.247757] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'406test # [ 0.251449] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8407second # [ 0.248758] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'408test # [ 0.252449] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16409second # [ 0.250757] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'410test # [ 0.254449] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24411second # [ 0.251757] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'412test # [ 0.255449] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format.413second # [ 0.253758] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256414second # [ 0.254757] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64415second # [ 0.256757] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512416second # [ 0.257757] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024417second # [ 0.258757] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8418second # [ 0.259757] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16419second # [ 0.260758] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24420second # [ 0.261757] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format.421test # [ 0.281528] Freeing SMP alternatives memory: 48K422test # [ 0.282451] pid_max: default: 32768 minimum: 301423test # [ 0.283493] LSM: initializing lsm=capability,landlock,yama,bpf,ima424test # [ 0.284532] landlock: Up and running.425test # [ 0.285449] Yama: becoming mindful.426test # [ 0.286529] LSM support for eBPF active427test # [ 0.287415] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)428test # [ 0.288466] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)429test # [ 0.290164] smpboot: CPU0: AMD Ryzen 9 7950X3D 16-Core Processor (family: 0x19, model: 0x61, stepping: 0x2)430second # [ 0.288493] Freeing SMP alternatives memory: 48K431second # [ 0.288760] pid_max: default: 32768 minimum: 301432test # [ 0.290863] Performance Events: Fam17h+ core perfctr, AMD PMU driver.433second # [ 0.289831] LSM: initializing lsm=capability,landlock,yama,bpf,ima434test # [ 0.291453] ... version: 2435second # [ 0.291829] landlock: Up and running.436test # [ 0.292344] ... bit width: 48437second # [ 0.292615] Yama: becoming mindful.438test # [ 0.293157] ... generic counters: 6439second # [ 0.293527] LSM support for eBPF active440test # [ 0.293455] ... generic bitmap: 000000000000003f441test # [ 0.294450] ... fixed-purpose counters: 0442second # [ 0.294464] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)443test # [ 0.295323] ... fixed-purpose bitmap: 0000000000000000444second # [ 0.295780] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)445test # [ 0.296345] ... value mask: 0000ffffffffffff446test # [ 0.297343] ... max period: 00007fffffffffff447test # [ 0.298349] ... global_ctrl mask: 000000000000003f448second # [ 0.298407] smpboot: CPU0: AMD Ryzen 9 7950X3D 16-Core Processor (family: 0x19, model: 0x61, stepping: 0x2)449test # [ 0.299423] signal: max sigframe size: 3376450second # [ 0.299145] Performance Events: Fam17h+ core perfctr, AMD PMU driver.451test # [ 0.300187] rcu: Hierarchical SRCU implementation.452second # [ 0.299762] ... version: 2453test # [ 0.300454] rcu: Max phase no-delay instances is 400.454second # [ 0.300662] ... bit width: 48455second # [ 0.301471] ... generic counters: 6456second # [ 0.301809] ... generic bitmap: 000000000000003f457second # [ 0.302759] ... fixed-purpose counters: 0458test # [ 0.304481] smp: Bringing up secondary CPUs ...459second # [ 0.303643] ... fixed-purpose bitmap: 0000000000000000460test # [ 0.305425] smp: Brought up 1 node, 1 CPU461second # [ 0.304656] ... value mask: 0000ffffffffffff462test # [ 0.306107] smpboot: Total of 1 processors activated (8384.24 BogoMIPS)463second # [ 0.305656] ... max period: 00007fffffffffff464second # [ 0.306655] ... global_ctrl mask: 000000000000003f465test # [ 0.306584] Memory: 941888K/1048024K available (18120K kernel code, 2728K rwdata, 13640K rodata, 3656K init, 3632K bss, 98712K reserved, 0K cma-reserved)466second # [ 0.307749] signal: max sigframe size: 3376467test # [ 0.307615] devtmpfs: initialized468second # [ 0.308509] rcu: Hierarchical SRCU implementation.469test # [ 0.308581] x86/mm: Memory block size: 128MB470second # [ 0.308763] rcu: Max phase no-delay instances is 400.471test # [ 0.310144] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)472test # [ 0.310471] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).473second # [ 0.312839] smp: Bringing up secondary CPUs ...474test # [ 0.311514] pinctrl core: initialized pinctrl subsystem475second # [ 0.313775] smp: Brought up 1 node, 1 CPU476test # [ 0.312692] PM: RTC time: 15:03:47, date: 2026-09-30477second # [ 0.314640] smpboot: Total of 1 processors activated (8384.24 BogoMIPS)478second # [ 0.315871] Memory: 941884K/1048024K available (18120K kernel code, 2728K rwdata, 13640K rodata, 3656K init, 3632K bss, 98708K reserved, 0K cma-reserved)479test # [ 0.315401] NET: Registered PF_NETLINK/PF_ROUTE protocol family480second # [ 0.317060] devtmpfs: initialized481test # [ 0.316670] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations482second # [ 0.317980] x86/mm: Memory block size: 128MB483test # [ 0.317490] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations484second # [ 0.319676] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)485test # [ 0.318580] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations486second # [ 0.320799] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).487test # [ 0.319462] audit: initializing netlink subsys (disabled)488second # [ 0.321836] pinctrl core: initialized pinctrl subsystem489test # [ 0.320722] thermal_sys: Registered thermal governor 'fair_share'490second # [ 0.323032] PM: RTC time: 15:03:47, date: 2026-09-30491test # [ 0.320724] thermal_sys: Registered thermal governor 'bang_bang'492test # [ 0.321452] thermal_sys: Registered thermal governor 'step_wise'493second # [ 0.325730] NET: Registered PF_NETLINK/PF_ROUTE protocol family494test # [ 0.322456] audit: type=2000 audit(1790780627.883:1): state=initialized audit_enabled=0 res=1495second # [ 0.327009] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations496test # [ 0.324453] thermal_sys: Registered thermal governor 'user_space'497second # [ 0.327776] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations498test # [ 0.324455] thermal_sys: Registered thermal governor 'power_allocator'499test # [ 0.325465] cpuidle: using governor menu500second # [ 0.328863] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations501second # [ 0.329765] audit: initializing netlink subsys (disabled)502test # [ 0.328125] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5503second # [ 0.330961] thermal_sys: Registered thermal governor 'fair_share'504test # [ 0.328679] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]505second # [ 0.330963] thermal_sys: Registered thermal governor 'bang_bang'506test # [ 0.329453] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry507second # [ 0.331761] audit: type=2000 audit(1790780627.886:1): state=initialized audit_enabled=0 res=1508test # [ 0.330462] PCI: Using configuration type 1 for base access509second # [ 0.333760] thermal_sys: Registered thermal governor 'step_wise'510test # [ 0.331628] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.511second # [ 0.333761] thermal_sys: Registered thermal governor 'user_space'512second # [ 0.334758] thermal_sys: Registered thermal governor 'power_allocator'513second # [ 0.335773] cpuidle: using governor menu514second # [ 0.338406] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5515test # [ 0.336643] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages516second # [ 0.338966] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]517test # [ 0.337451] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page518second # [ 0.339760] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry519second # [ 0.340767] PCI: Using configuration type 1 for base access520second # [ 0.341915] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.521test # [ 0.342450] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages522test # [ 0.343451] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page523second # [ 0.348779] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages524second # [ 0.349758] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page525test # [ 0.351817] ACPI: Added _OSI(Module Device)526test # [ 0.352452] ACPI: Added _OSI(Processor Device)527test # [ 0.353366] ACPI: Added _OSI(Processor Aggregator Device)528second # [ 0.354758] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages529second # [ 0.355759] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page530test # [ 0.358273] ACPI: 1 ACPI AML tables successfully acquired and loaded531second # [ 0.362419] ACPI: Added _OSI(Module Device)532second # [ 0.362759] ACPI: Added _OSI(Processor Device)533second # [ 0.363655] ACPI: Added _OSI(Processor Aggregator Device)534test # [ 0.364256] ACPI: Interpreter enabled535test # [ 0.364464] ACPI: PM: (supports S0 S3 S4 S5)536test # [ 0.365337] ACPI: Using IOAPIC for interrupt routing537test # [ 0.366270] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug538test # [ 0.368122] PCI: Using E820 reservations for host bridge windows539test # [ 0.368561] ACPI: Enabled 2 GPEs in block 00 to 3F540second # [ 0.370443] ACPI: 1 ACPI AML tables successfully acquired and loaded541second # [ 0.373507] ACPI: Interpreter enabled542test # [ 0.372590] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])543second # [ 0.373770] ACPI: PM: (supports S0 S3 S4 S5)544second # [ 0.374660] ACPI: Using IOAPIC for interrupt routing545test # [ 0.373456] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]546second # [ 0.375586] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug547test # [ 0.374511] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]548test # [ 0.375550] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]549second # [ 0.377437] PCI: Using E820 reservations for host bridge windows550test # [ 0.376845] PCI host bridge to bus 0000:00551second # [ 0.377868] ACPI: Enabled 2 GPEs in block 00 to 3F552test # [ 0.377455] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]553test # [ 0.378452] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]554test # [ 0.379451] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]555second # [ 0.381920] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])556test # [ 0.380452] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]557second # [ 0.382763] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]558test # [ 0.381452] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]559second # [ 0.383818] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]560test # [ 0.382452] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]561second # [ 0.384855] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]562test # [ 0.383452] pci_bus 0000:00: root bus resource [bus 00-ff]563second # [ 0.386159] PCI host bridge to bus 0000:00564test # [ 0.384559] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint565second # [ 0.386763] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]566second # [ 0.387759] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]567test # [ 0.386115] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint568second # [ 0.388759] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]569second # [ 0.389759] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]570second # [ 0.390759] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]571test # [ 0.388445] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]572second # [ 0.391759] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]573test # [ 0.389472] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]574second # [ 0.392759] pci_bus 0000:00: root bus resource [bus 00-ff]575test # [ 0.390490] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]576second # [ 0.393846] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint577test # [ 0.391622] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]578second # [ 0.395418] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint579test # [ 0.393187] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint580test # [ 0.395461] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]581second # [ 0.397724] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]582test # [ 0.396465] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]583second # [ 0.398778] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]584test # [ 0.397538] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]585second # [ 0.399797] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]586test # [ 0.398502] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]587second # [ 0.400918] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]588test # [ 0.400446] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint589second # [ 0.402482] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint590test # [ 0.402461] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]591second # [ 0.404769] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]592test # [ 0.403467] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]593second # [ 0.405815] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]594test # [ 0.404490] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]595second # [ 0.406834] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]596second # [ 0.407769] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]597test # [ 0.406339] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint598second # [ 0.409750] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint599test # [ 0.409461] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]600test # [ 0.410468] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]601second # [ 0.411769] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]602test # [ 0.411495] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]603second # [ 0.412769] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]604second # [ 0.413798] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]605test # [ 0.413337] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint606second # [ 0.415635] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint607test # [ 0.415461] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]608test # [ 0.416463] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]609second # [ 0.418769] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]610test # [ 0.417501] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]611second # [ 0.419770] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]612test # [ 0.418467] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]613second # [ 0.420797] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]614test # [ 0.420328] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint615second # [ 0.422620] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint616test # [ 0.422303] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]617test # [ 0.423460] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]618second # [ 0.424769] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]619second # [ 0.425770] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]620test # [ 0.425344] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint621second # [ 0.426797] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]622second # [ 0.427769] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]623test # [ 0.427262] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]624test # [ 0.428447] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]625second # [ 0.429611] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint626second # [ 0.431554] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]627test # [ 0.430366] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint628second # [ 0.432747] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]629test # [ 0.432470] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]630test # [ 0.433490] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]631second # [ 0.434613] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint632second # [ 0.436565] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]633test # [ 0.435308] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint634second # [ 0.437755] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]635test # [ 0.437269] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]636test # [ 0.438435] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]637second # [ 0.439667] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint638second # [ 0.441737] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]639test # [ 0.440314] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint640second # [ 0.442741] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]641test # [ 0.442461] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]642test # [ 0.443466] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]643second # [ 0.444777] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint644test # [ 0.444494] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]645second # [ 0.446538] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]646second # [ 0.447738] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]647test # [ 0.446311] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint648test # [ 0.448438] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]649second # [ 0.449606] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint650test # [ 0.449300] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]651second # [ 0.451769] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]652test # [ 0.450439] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]653second # [ 0.452769] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]654second # [ 0.453796] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]655test # [ 0.452326] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint656test # [ 0.454049] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]657second # [ 0.455604] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint658second # [ 0.457722] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]659second # [ 0.458597] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]660second # [ 0.459742] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]661second # [ 0.461743] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint662second # [ 0.463347] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]663test # [ 0.454704] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint664test # [ 0.457078] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]665test # [ 0.457690] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint666test # [ 0.459084] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]667test # [ 0.459745] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint668test # [ 0.461022] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]669second # [ 0.464005] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint670test # [ 0.461771] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint671second # [ 0.466368] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]672test # [ 0.462935] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO673second # [ 0.467014] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint674second # [ 0.468404] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]675test # [ 0.463807] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint676second # [ 0.469049] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint677test # [ 0.465293] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]678test # [ 0.466441] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]679second # [ 0.470348] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]680test # [ 0.467893] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint681second # [ 0.471082] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint682test # [ 0.469053] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]683second # [ 0.472236] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO684second # [ 0.473106] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint685second # [ 0.474587] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]686second # [ 0.475758] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]687test # [ 0.474133] ACPI: PCI: Interrupt link LNKA configured for IRQ 10688test # [ 0.474585] ACPI: PCI: Interrupt link LNKB configured for IRQ 10689second # [ 0.477233] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint690test # [ 0.475566] ACPI: PCI: Interrupt link LNKC configured for IRQ 11691second # [ 0.478362] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]692test # [ 0.476562] ACPI: PCI: Interrupt link LNKD configured for IRQ 11693test # [ 0.477565] ACPI: PCI: Interrupt link LNKE configured for IRQ 10694test # [ 0.478562] ACPI: PCI: Interrupt link LNKF configured for IRQ 10695test # [ 0.479565] ACPI: PCI: Interrupt link LNKG configured for IRQ 11696test # [ 0.480563] ACPI: PCI: Interrupt link LNKH configured for IRQ 11697second # [ 0.483302] ACPI: PCI: Interrupt link LNKA configured for IRQ 10698test # [ 0.481498] ACPI: PCI: Interrupt link GSIA configured for IRQ 16699second # [ 0.483870] ACPI: PCI: Interrupt link LNKB configured for IRQ 10700test # [ 0.482467] ACPI: PCI: Interrupt link GSIB configured for IRQ 17701second # [ 0.484872] ACPI: PCI: Interrupt link LNKC configured for IRQ 11702test # [ 0.483466] ACPI: PCI: Interrupt link GSIC configured for IRQ 18703second # [ 0.485869] ACPI: PCI: Interrupt link LNKD configured for IRQ 11704test # [ 0.484463] ACPI: PCI: Interrupt link GSID configured for IRQ 19705second # [ 0.486869] ACPI: PCI: Interrupt link LNKE configured for IRQ 10706test # [ 0.485467] ACPI: PCI: Interrupt link GSIE configured for IRQ 20707second # [ 0.487871] ACPI: PCI: Interrupt link LNKF configured for IRQ 10708test # [ 0.486466] ACPI: PCI: Interrupt link GSIF configured for IRQ 21709second # [ 0.488872] ACPI: PCI: Interrupt link LNKG configured for IRQ 11710test # [ 0.487463] ACPI: PCI: Interrupt link GSIG configured for IRQ 22711second # [ 0.489871] ACPI: PCI: Interrupt link LNKH configured for IRQ 11712test # [ 0.488463] ACPI: PCI: Interrupt link GSIH configured for IRQ 23713second # [ 0.490804] ACPI: PCI: Interrupt link GSIA configured for IRQ 16714test # [ 0.490251] iommu: Default domain type: Translated715second # [ 0.491771] ACPI: PCI: Interrupt link GSIB configured for IRQ 17716test # [ 0.491226] iommu: DMA domain TLB invalidation policy: lazy mode717second # [ 0.492774] ACPI: PCI: Interrupt link GSIC configured for IRQ 18718test # [ 0.492619] ACPI: bus type USB registered719second # [ 0.493771] ACPI: PCI: Interrupt link GSID configured for IRQ 19720test # [ 0.493506] usbcore: registered new interface driver usbfs721second # [ 0.494771] ACPI: PCI: Interrupt link GSIE configured for IRQ 20722test # [ 0.494468] usbcore: registered new interface driver hub723second # [ 0.495774] ACPI: PCI: Interrupt link GSIF configured for IRQ 21724test # [ 0.495471] usbcore: registered new device driver usb725second # [ 0.496775] ACPI: PCI: Interrupt link GSIG configured for IRQ 22726test # [ 0.497104] NetLabel: Initializing727second # [ 0.497773] ACPI: PCI: Interrupt link GSIH configured for IRQ 23728test # [ 0.497455] NetLabel: domain hash size = 128729test # [ 0.498355] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO730second # [ 0.499549] iommu: Default domain type: Translated731test # [ 0.499393] NetLabel: unlabeled traffic allowed by default732second # [ 0.500557] iommu: DMA domain TLB invalidation policy: lazy mode733test # [ 0.500351] PCI: Using ACPI for IRQ routing734second # [ 0.501940] ACPI: bus type USB registered735second # [ 0.502809] usbcore: registered new interface driver usbfs736second # [ 0.503773] usbcore: registered new interface driver hub737second # [ 0.504768] usbcore: registered new device driver usb738second # [ 0.506416] NetLabel: Initializing739second # [ 0.506763] NetLabel: domain hash size = 128740second # [ 0.507655] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO741second # [ 0.508695] NetLabel: unlabeled traffic allowed by default742second # [ 0.509668] PCI: Using ACPI for IRQ routing743test # [ 0.575531] pci 0000:00:01.0: vgaarb: setting as boot VGA device744test # [ 0.576447] pci 0000:00:01.0: vgaarb: bridge control possible745test # [ 0.576447] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none746test # [ 0.576454] vgaarb: loaded747test # [ 0.577244] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0748test # [ 0.578235] hpet0: 3 comparators, 64-bit 100.000000 MHz counter749second # [ 0.586288] pci 0000:00:01.0: vgaarb: setting as boot VGA device750test # [ 0.582537] clocksource: Switched to clocksource kvm-clock751second # [ 0.586755] pci 0000:00:01.0: vgaarb: bridge control possible752second # [ 0.586755] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none753test # [ 0.585626] VFS: Disk quotas dquot_6.6.0754second # [ 0.586761] vgaarb: loaded755test # [ 0.586495] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)756second # [ 0.587581] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0757test # [ 0.588078] pnp: PnP ACPI init758second # [ 0.588576] hpet0: 3 comparators, 64-bit 100.000000 MHz counter759test # [ 0.588970] ACPI: IRQ 4 override to edge(!), high(!)760test # [ 0.590131] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved761test # [ 0.591962] pnp: PnP ACPI: found 5 devices762second # [ 0.592856] clocksource: Switched to clocksource kvm-clock763second # [ 0.596039] VFS: Disk quotas dquot_6.6.0764second # [ 0.596916] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)765second # [ 0.598494] pnp: PnP ACPI init766test # [ 0.599131] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns767second # [ 0.599373] ACPI: IRQ 4 override to edge(!), high(!)768test # [ 0.600904] clocksource: Switched to clocksource acpi_pm769second # [ 0.600518] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved770test # [ 0.602079] NET: Registered PF_INET protocol family771second # [ 0.602354] pnp: PnP ACPI: found 5 devices772test # [ 0.603316] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)773second # [ 0.609495] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns774second # [ 0.611283] clocksource: Switched to clocksource acpi_pm775second # [ 0.612499] NET: Registered PF_INET protocol family776second # [ 0.613694] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)777test # [ 0.616716] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)778test # [ 0.618544] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)779test # [ 0.620161] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)780test # [ 0.621816] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)781test # [ 0.623338] TCP: Hash tables configured (established 8192 bind 8192)782test # [ 0.624698] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)783test # [ 0.626306] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)784test # [ 0.627696] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)785second # [ 0.627011] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)786test # [ 0.629187] NET: Registered PF_UNIX/PF_LOCAL protocol family787second # [ 0.628837] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)788test # [ 0.630415] NET: Registered PF_XDP protocol family789second # [ 0.630438] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)790test # [ 0.631464] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]791test # [ 0.632752] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]792second # [ 0.632079] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)793test # [ 0.634025] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]794second # [ 0.633599] TCP: Hash tables configured (established 8192 bind 8192)795test # [ 0.635449] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]796second # [ 0.634955] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)797test # [ 0.636841] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]798second # [ 0.636496] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)799test # [ 0.638260] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]800second # [ 0.637881] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)801second # [ 0.639351] NET: Registered PF_UNIX/PF_LOCAL protocol family802test # [ 0.640212] ACPI: \_SB_.GSIA: Enabled at IRQ 16803second # [ 0.640586] NET: Registered PF_XDP protocol family804test # [ 0.642390] ACPI: \_SB_.GSIB: Enabled at IRQ 17805second # [ 0.641632] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]806second # [ 0.642900] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]807test # [ 0.644404] ACPI: \_SB_.GSIC: Enabled at IRQ 18808second # [ 0.644158] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]809test # [ 0.646387] ACPI: \_SB_.GSID: Enabled at IRQ 19810second # [ 0.645541] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]811second # [ 0.646930] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]812test # [ 0.648107] PCI: CLS 0 bytes, default 64813second # [ 0.648312] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]814test # [ 0.649200] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3c6d4d0b6d3, max_idle_ns: 440795255641 ns815second # [ 0.650282] ACPI: \_SB_.GSIA: Enabled at IRQ 16816test # [ 0.651321] Trying to unpack rootfs image as initramfs...817second # [ 0.652242] ACPI: \_SB_.GSIB: Enabled at IRQ 17818second # [ 0.654088] ACPI: \_SB_.GSIC: Enabled at IRQ 18819second # [ 0.655950] ACPI: \_SB_.GSID: Enabled at IRQ 19820second # [ 0.657632] PCI: CLS 0 bytes, default 64821second # [ 0.658659] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3c6d4d0b6d3, max_idle_ns: 440795255641 ns822second # [ 0.660700] Trying to unpack rootfs image as initramfs...823test # [ 0.687614] Initialise system trusted keyrings824test # [ 0.690305] workingset: timestamp_bits=40 max_order=18 bucket_order=0825second # [ 0.696820] Initialise system trusted keyrings826second # [ 0.699512] workingset: timestamp_bits=40 max_order=18 bucket_order=0827test # [ 0.706584] Key type asymmetric registered828test # [ 0.711280] Asymmetric key parser 'x509' registered829test # [ 0.712366] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)830test # [ 0.715375] io scheduler mq-deadline registered831second # [ 0.715693] Key type asymmetric registered832test # [ 0.718268] io scheduler kyber registered833second # [ 0.718522] Asymmetric key parser 'x509' registered834second # [ 0.719606] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)835test # [ 0.720719] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled836test # [ 0.724267] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A837second # [ 0.725591] io scheduler mq-deadline registered838second # [ 0.726576] io scheduler kyber registered839test # [ 0.729533] Linux agpgart interface v0.103840test # [ 0.730470] ACPI: bus type drm_connector registered841second # [ 0.729946] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled842test # [ 0.733590] usbcore: registered new interface driver usbserial_generic843second # [ 0.733478] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A844test # [ 0.734907] usbserial: USB Serial support registered for generic845test # [ 0.738270] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.846second # [ 0.738749] Linux agpgart interface v0.103847test # [ 0.738270] Please enable it if your BIOS has the CPPC option.848second # [ 0.739679] ACPI: bus type drm_connector registered849test # [ 0.740941] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled850second # [ 0.742842] usbcore: registered new interface driver usbserial_generic851second # [ 0.744156] usbserial: USB Serial support registered for generic852test # [ 0.745381] drop_monitor: Initializing network drop monitor service853test # [ 0.746769] NET: Registered PF_INET6 protocol family854second # [ 0.747491] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.855second # [ 0.747491] Please enable it if your BIOS has the CPPC option.856test # [ 0.750629] Segment Routing with IPv6857second # [ 0.750154] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled858test # [ 0.754270] In-situ OAM (IOAM) with IPv6859test # [ 0.755401] IPI shorthand broadcast: enabled860second # [ 0.754601] drop_monitor: Initializing network drop monitor service861second # [ 0.755973] NET: Registered PF_INET6 protocol family862second # [ 0.759850] Segment Routing with IPv6863test # [ 0.760709] sched_clock: Marking stable (588020890, 172229456)->(843961512, -83711166)864second # [ 0.763481] In-situ OAM (IOAM) with IPv6865second # [ 0.764618] IPI shorthand broadcast: enabled866test # [ 0.767544] registered taskstats version 1867test # [ 0.768593] Loading compiled-in X.509 certificates868second # [ 0.769918] sched_clock: Marking stable (589020478, 180440241)->(852976105, -83515386)869second # [ 0.776751] registered taskstats version 1870second # [ 0.777790] Loading compiled-in X.509 certificates871test # [ 0.784407] Demotion targets for Node 0: null872test # [ 0.785448] Key type .fscrypt registered873test # [ 0.788257] Key type fscrypt-provisioning registered874test # [ 0.789375] ima: No TPM chip found, activating TPM-bypass!875test # [ 0.792276] ima: Allocated hash algorithm: sha1876second # [ 0.792526] Demotion targets for Node 0: null877test # [ 0.793265] ima: No architecture policies found878second # [ 0.793593] Key type .fscrypt registered879second # [ 0.794421] Key type fscrypt-provisioning registered880test # [ 0.796399] PM: Magic number: 10:378:84881test # [ 0.797971] RAS: Correctable Errors collector initialized.882second # [ 0.797583] ima: No TPM chip found, activating TPM-bypass!883second # [ 0.798730] ima: Allocated hash algorithm: sha1884second # [ 0.803496] ima: No architecture policies found885second # [ 0.804649] PM: Magic number: 10:378:84886test # [ 0.807290] clk: Disabling unused clocks887test # [ 0.808182] PM: genpd: Disabling unused power domains888second # [ 0.808951] RAS: Correctable Errors collector initialized.889second # [ 0.816901] clk: Disabling unused clocks890second # [ 0.817770] PM: genpd: Disabling unused power domains891test # [ 0.910084] Freeing initrd memory: 30212K892test # [ 0.912858] Freeing unused decrypted memory: 2028K893test # [ 0.915394] Freeing unused kernel image (initmem) memory: 3656K894test # [ 0.916707] Write protecting the kernel read-only data: 32768k895test # [ 0.918186] Freeing unused kernel image (text/rodata gap) memory: 308K896test # [ 0.919980] Freeing unused kernel image (rodata/data gap) memory: 696K897second # [ 0.920481] Freeing initrd memory: 30208K898second # [ 0.923230] Freeing unused decrypted memory: 2028K899second # [ 0.925796] Freeing unused kernel image (initmem) memory: 3656K900second # [ 0.927106] Write protecting the kernel read-only data: 32768k901second # [ 0.928597] Freeing unused kernel image (text/rodata gap) memory: 308K902second # [ 0.930243] Freeing unused kernel image (rodata/data gap) memory: 696K903test # [ 0.952992] x86/mm: Checked W+X mappings: passed, no W+X pages found.904test # [ 0.954383] Run /init as init process905test # [ 0.962974] systemd[1]: Inserted module 'autofs4'906second # [ 0.963014] x86/mm: Checked W+X mappings: passed, no W+X pages found.907second # [ 0.964351] Run /init as init process908second # [ 0.972984] systemd[1]: Inserted module 'autofs4'909test # [ 0.979009] fuse: init (API version 7.45)910test # [ 0.985195] ACPI: \_SB_.GSIG: Enabled at IRQ 22911test # [ 0.987875] ACPI: \_SB_.GSIH: Enabled at IRQ 23912second # [ 0.987949] fuse: init (API version 7.45)913test # [ 0.991141] ACPI: \_SB_.GSIE: Enabled at IRQ 20914test # [ 0.993558] ACPI: \_SB_.GSIF: Enabled at IRQ 21915second # [ 0.993680] ACPI: \_SB_.GSIG: Enabled at IRQ 22916second # [ 0.996161] ACPI: \_SB_.GSIH: Enabled at IRQ 23917test # [ 0.998801] virtiofs virtio5: discovered new tag: nix-store918second # [ 0.999342] ACPI: \_SB_.GSIE: Enabled at IRQ 20919test # [ 1.000908] virtiofs virtio5: virtio_fs_setup_dax: No cache capability920second # [ 1.001920] ACPI: \_SB_.GSIF: Enabled at IRQ 21921second # [ 1.007181] virtiofs virtio5: discovered new tag: nix-store922test # [ 1.008357] virtiofs virtio6: discovered new tag: shared923second # [ 1.009237] virtiofs virtio5: virtio_fs_setup_dax: No cache capability924test # [ 1.010405] virtiofs virtio6: virtio_fs_setup_dax: No cache capability925test # [ 1.013712] virtiofs virtio7: discovered new tag: xchg926test # [ 1.015666] virtiofs virtio7: virtio_fs_setup_dax: No cache capability927second # [ 1.016632] virtiofs virtio6: discovered new tag: shared928second # [ 1.018632] virtiofs virtio6: virtio_fs_setup_dax: No cache capability929second # [ 1.021977] virtiofs virtio7: discovered new tag: xchg930second # [ 1.023944] virtiofs virtio7: virtio_fs_setup_dax: No cache capability931test # [ 1.033597] systemd[1]: Successfully made /usr/ read-only.932second # [ 1.042957] systemd[1]: Successfully made /usr/ read-only.933test # [ 1.369412] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)934test # [ 1.375202] systemd[1]: Detected virtualization kvm.935test # [ 1.376303] systemd[1]: Detected architecture x86-64.936test # [ 1.377361] systemd[1]: Running in initrd.937test # [ 1.378478] systemd[1]: Initializing machine ID from random generator.938test # [ 1.379870] systemd[1]: Hostname set to <test>.939second # [ 1.379094] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)940second # [ 1.384965] systemd[1]: Detected virtualization kvm.941second # [ 1.386034] systemd[1]: Detected architecture x86-64.942second # [ 1.387100] systemd[1]: Running in initrd.943second # [ 1.388194] systemd[1]: Initializing machine ID from random generator.944second # [ 1.389619] systemd[1]: Hostname set to <second>.945test # [ 1.433405] systemd[1]: bpf-restrict-fs: LSM BPF program attached946second # [ 1.441932] systemd[1]: bpf-restrict-fs: LSM BPF program attached947test # [ 1.461643] systemd[1]: Queued start job for default target Initrd Default Target.948test # [ 1.466377] systemd[1]: Created slice Slice /system/modprobe.949test # [ 1.467746] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.950test # [ 1.469385] systemd[1]: Expecting device /dev/disk/by-label/nixos...951test # [ 1.470704] systemd[1]: Reached target Path Units.952second # [ 1.470142] systemd[1]: Queued start job for default target Initrd Default Target.953test # [ 1.471844] systemd[1]: Reached target Slice Units.954test # [ 1.472969] systemd[1]: Reached target Swaps.955test # [ 1.473969] systemd[1]: Reached target Timer Units.956test # [ 1.475119] systemd[1]: Listening on D-Bus System Message Bus Socket.957second # [ 1.474766] systemd[1]: Created slice Slice /system/modprobe.958test # [ 1.476568] systemd[1]: Listening on Journal Socket (/dev/log).959second # [ 1.476136] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.960test # [ 1.477938] systemd[1]: Listening on Journal Sockets.961second # [ 1.477807] systemd[1]: Expecting device /dev/disk/by-label/nixos...962second # [ 1.479311] systemd[1]: Reached target Path Units.963second # [ 1.480373] systemd[1]: Reached target Slice Units.964second # [ 1.481470] systemd[1]: Reached target Swaps.965second # [ 1.482427] systemd[1]: Reached target Timer Units.966second # [ 1.483608] systemd[1]: Listening on D-Bus System Message Bus Socket.967second # [ 1.485044] systemd[1]: Listening on Journal Socket (/dev/log).968second # [ 1.486412] systemd[1]: Listening on Journal Sockets.969test # [ 1.479123] systemd[1]: Listening on udev Control Socket.970test # [ 1.488256] systemd[1]: Listening on udev Kernel Socket.971test # [ 1.489406] systemd[1]: Reached target Socket Units.972test # [ 1.491100] systemd[1]: Starting Create List of Static Device Nodes...973second # [ 1.487638] systemd[1]: Listening on udev Control Socket.974second # [ 1.497042] systemd[1]: Listening on udev Kernel Socket.975test # [ 1.498055] systemd[1]: Starting Load Kernel Module configfs...976second # [ 1.498410] systemd[1]: Reached target Socket Units.977second # [ 1.500507] systemd[1]: Starting Create List of Static Device Nodes...978test # [ 1.508872] systemd[1]: Starting Journal Service...979second # [ 1.508006] systemd[1]: Starting Load Kernel Module configfs...980test # [ 1.515316] systemd[1]: Starting Load Kernel Modules...981test # [ 1.517610] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os982second # [ 1.517802] systemd[1]: Starting Journal Service...983second # [ 1.522163] systemd[1]: Starting Load Kernel Modules...984test # [ 1.523389] systemd[1]: Starting Coldplug All udev Devices...985second # [ 1.524698] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os986second # [ 1.529986] systemd[1]: Starting Coldplug All udev Devices...987test # [ 1.537311] systemd[1]: Finished Create List of Static Device Nodes.988test # [ 1.541803] systemd[1]: modprobe@configfs.service: Deactivated successfully.989second # [ 1.544528] systemd[1]: Finished Create List of Static Device Nodes.990test # [ 1.548165] systemd-journald[65]: Collecting audit messages is disabled.991second # [ 1.549083] systemd[1]: modprobe@configfs.service: Deactivated successfully.992test # [ 1.551677] systemd[1]: Finished Load Kernel Module configfs.993second # [ 1.554688] systemd-journald[65]: Collecting audit messages is disabled.994test # [ 1.555765] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config995second # [ 1.557931] systemd[1]: Finished Load Kernel Module configfs.996test # [ 1.560114] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.997test # [ 1.567991] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...998second # [ 1.566962] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config999test # [ 1.572388] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1000second # [ 1.572137] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1001second # [ 1.579534] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1002second # [ 1.583598] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1003test # [ 1.599264] systemd[1]: Finished Load Kernel Modules.1004test # [ 1.607397] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1005second # [ 1.612992] systemd[1]: Finished Load Kernel Modules.1006test # [ 1.615405] systemd[1]: Starting Apply Kernel Variables...1007second # [ 1.617571] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1008second # [ 1.625623] systemd[1]: Starting Apply Kernel Variables...1009test # [ 1.628790] systemd[1]: Starting Create Static Device Nodes in /dev...1010second # [ 1.639024] systemd[1]: Starting Create Static Device Nodes in /dev...1011test # [ 1.644657] systemd[1]: Finished Apply Kernel Variables.1012test # [ 1.657353] systemd[1]: Finished Create Static Device Nodes in /dev.1013second # [ 1.657532] systemd[1]: Finished Apply Kernel Variables.1014test # [ 1.662498] systemd[1]: Reached target Preparation for Local File Systems.1015test # [ 1.666339] systemd[1]: Reached target Local File Systems.1016second # [ 1.666579] systemd[1]: Finished Create Static Device Nodes in /dev.1017second # [ 1.671703] systemd[1]: Reached target Preparation for Local File Systems.1018test # [ 1.673460] systemd[1]: Starting Rule-based Manager for Device Events and Files...1019second # [ 1.676536] systemd[1]: Reached target Local File Systems.1020second # [ 1.683648] systemd[1]: Starting Rule-based Manager for Device Events and Files...1021test # [ 1.513241] systemd-modules-load[66]: Inserted module 'dm_mod'1022test # [ 1.514852] systemd-modules-load[66]: Inserted module 'virtio_balloon'1023test # [ 1.517339] systemd-modules-load[66]: Inserted module 'virtio_gpu'1024test # [ 1.691372] systemd[1]: Started Journal Service.1025second # [ 1.509811] systemd-modules-load[66]: Inserted module 'dm_mod'1026second # [ 1.513239] systemd-modules-load[66]: Inserted module 'virtio_balloon'1027second # [ 1.514579] systemd-modules-load[66]: Inserted module 'virtio_gpu'1028second # [ 1.696177] systemd[1]: Started Journal Service.1029test # [ 1.534083] systemd[1]: Starting Create System Files and Directories...1030second # [ 1.532528] systemd[1]: Starting Create System Files and Directories...1031test # [ 1.552995] systemd-udevd[73]: Using default interface naming scheme 'v261'.1032test # [ 1.560116] systemd[1]: Finished Create System Files and Directories.1033second # [ 1.554636] systemd[1]: Finished Create System Files and Directories.1034second # [ 1.558482] systemd-udevd[73]: Using default interface naming scheme 'v261'.1035test # [ 1.580081] systemd[1]: Started Rule-based Manager for Device Events and Files.1036second # [ 1.581477] systemd[1]: Started Rule-based Manager for Device Events and Files.1037test # [ 1.590915] systemd[1]: Finished Coldplug All udev Devices.1038test # [ 1.592071] systemd[1]: Reached target System Initialization.1039test # [ 1.593127] systemd[1]: Reached target Basic System.1040second # [ 1.587745] systemd[1]: Finished Coldplug All udev Devices.1041second # [ 1.588844] systemd[1]: Reached target System Initialization.1042second # [ 1.589901] systemd[1]: Reached target Basic System.1043test # [ 1.966499] virtio_blk virtio2: 1/0/0 default/read/poll queues1044second # [ 1.967311] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121045test # [ 1.980575] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1046second # [ 1.981115] virtio_blk virtio2: 1/0/0 default/read/poll queues1047second # [ 1.984146] serio: i8042 KBD port at 0x60,0x64 irq 11048test # [ 1.984937] ehci-pci 0000:00:1d.7: EHCI Host Controller1049test # [ 1.985792] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11050test # [ 1.987648] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001051second # [ 1.991090] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1052test # [ 1.992618] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121053second # [ 1.992963] serio: i8042 AUX port at 0x60,0x64 irq 121054test # [ 1.995272] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001055second # [ 1.995966] uhci_hcd 0000:00:1d.0: UHCI Host Controller1056test # [ 1.998295] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181057test # [ 1.999559] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11058test # [ 2.002971] serio: i8042 KBD port at 0x60,0x64 irq 11059test # [ 2.003863] usb usb1: Product: EHCI Host Controller1060test # [ 2.004766] usb usb1: Manufacturer: Linux 6.18.54 ehci_hcd1061test # [ 2.006254] usb usb1: SerialNumber: 0000:00:1d.71062second # [ 2.006610] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 11063test # [ 2.008196] hub 1-0:1.0: USB hub found1064test # [ 2.009926] hub 1-0:1.0: 6 ports detected1065second # [ 2.014590] uhci_hcd 0000:00:1d.0: detected 2 ports1066test # [ 2.015630] uhci_hcd 0000:00:1d.0: UHCI Host Controller1067test # [ 2.016469] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21068test # [ 2.018393] serio: i8042 AUX port at 0x60,0x64 irq 121069second # [ 2.019578] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801070test # [ 2.026781] uhci_hcd 0000:00:1d.0: detected 2 ports1071second # [ 2.025970] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181072second # [ 2.027232] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11073test # [ 2.034371] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801074test # [ 2.036215] SCSI subsystem initialized1075second # [ 2.039544] usb usb1: Product: UHCI Host Controller1076second # [ 2.040309] usb usb1: Manufacturer: Linux 6.18.54 uhci_hcd1077test # [ 2.042702] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181078second # [ 2.046877] usb usb1: SerialNumber: 0000:00:1d.01079second # [ 2.048293] SCSI subsystem initialized1080test # [ 2.053699] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11081second # [ 1.875589] systemd[1]: Starting Virtual Console Setup...1082second # [ 1.877449] (udev-worker)[89]: Network interface NamePolicy= disabled on kernel command line.1083test # [ 1.886591] (udev-worker)[85]: Network interface NamePolicy= disabled on kernel command line.1084second # [ 2.059475] hub 1-0:1.0: USB hub found1085test # [ 1.892110] (udev-worker)[88]: Network interface NamePolicy= disabled on kernel command line.1086test # [ 1.894076] systemd[1]: Starting Virtual Console Setup...1087second # [ 1.888132] (udev-worker)[84]: Network interface NamePolicy= disabled on kernel command line.1088second # [ 2.070487] hub 1-0:1.0: 2 ports detected1089test # [ 2.072738] usb usb2: Product: UHCI Host Controller1090second # [ 2.081679] ehci-pci 0000:00:1d.7: EHCI Host Controller1091second # [ 2.082556] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 21092second # [ 2.085689] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001093test # [ 2.087399] usb usb2: Manufacturer: Linux 6.18.54 uhci_hcd1094second # [ 1.910389] systemd-vconsole-setup[96]: Configuration of first virtual console was skipped, ignoring remaining ones.1095second # [ 2.094499] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001096test # [ 1.923150] systemd-vconsole-setup[96]: Configuration of first virtual console was skipped, ignoring remaining ones.1097second # [ 1.917073] systemd[1]: Finished Virtual Console Setup.1098test # [ 1.926200] systemd[1]: Finished Virtual Console Setup.1099second # [ 2.099028] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181100second # [ 2.100343] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11101second # [ 2.104474] usb usb2: Product: EHCI Host Controller1102test # [ 2.105918] usb usb2: SerialNumber: 0000:00:1d.01103second # [ 2.105265] usb usb2: Manufacturer: Linux 6.18.54 ehci_hcd1104second # [ 2.107465] usb usb2: SerialNumber: 0000:00:1d.71105second # [ 2.109346] hub 2-0:1.0: USB hub found1106second # [ 2.110317] hub 2-0:1.0: 6 ports detected1107test # [ 2.114265] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01108test # [ 2.115829] hub 2-0:1.0: USB hub found1109test # [ 2.118997] hub 2-0:1.0: 2 ports detected1110test # [ 2.132281] uhci_hcd 0000:00:1d.1: UHCI Host Controller1111second # [ 2.132694] hub 1-0:1.0: USB hub found1112test # [ 2.133107] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31113second # [ 2.133804] hub 1-0:1.0: 2 ports detected1114second # [ 2.135005] uhci_hcd 0000:00:1d.1: UHCI Host Controller1115test # [ 1.963290] systemd[1]: Found device /dev/disk/by-label/nixos.1116test # [ 1.964639] systemd[1]: Reached target Initrd Root Device.1117test # [ 1.967164] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1118second # [ 2.140870] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31119second # [ 2.144488] uhci_hcd 0000:00:1d.1: detected 2 ports1120test # [ 2.145120] uhci_hcd 0000:00:1d.1: detected 2 ports1121second # [ 2.145378] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01122test # [ 2.147802] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01123second # [ 2.150085] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01124test # [ 2.152450] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181125second # [ 2.155567] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181126test # [ 2.157297] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11127test # [ 2.159281] usb usb3: Product: UHCI Host Controller1128test # [ 2.160075] usb usb3: Manufacturer: Linux 6.18.54 uhci_hcd1129second # [ 2.162369] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11130test # [ 1.994323] systemd-fsck[104]: nixos: clean, 12/65536 files, 13019/262144 blocks1131second # [ 2.166780] usb usb3: Product: UHCI Host Controller1132second # [ 2.169244] usb usb3: Manufacturer: Linux 6.18.54 uhci_hcd1133test # [ 2.170280] usb usb3: SerialNumber: 0000:00:1d.11134second # [ 2.171461] usb usb3: SerialNumber: 0000:00:1d.11135test # [ 2.001330] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1136second # [ 2.174751] hub 3-0:1.0: USB hub found1137test # [ 2.175983] hub 3-0:1.0: USB hub found1138test # [ 2.178301] hub 3-0:1.0: 2 ports detected1139second # [ 1.999076] systemd[1]: Found device /dev/disk/by-label/nixos.1140second # [ 2.000262] systemd[1]: Reached target Initrd Root Device.1141second # [ 2.002069] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1142second # [ 2.183888] hub 3-0:1.0: 2 ports detected1143test # [ 2.185567] uhci_hcd 0000:00:1d.2: UHCI Host Controller1144test # [ 2.188090] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1145second # [ 2.189694] uhci_hcd 0000:00:1d.2: UHCI Host Controller1146test # [ 2.190131] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41147second # [ 2.190575] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41148test # [ 2.192160] uhci_hcd 0000:00:1d.2: detected 2 ports1149test # [ 2.193326] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1150test # [ 2.194681] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01151test # [ 2.196191] ahci 0000:00:1f.2: flags: 64bit ncq only1152test # [ 2.198610] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181153second # [ 2.199580] uhci_hcd 0000:00:1d.2: detected 2 ports1154second # [ 2.202786] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01155test # [ 2.205701] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11156test # [ 2.207015] scsi host0: ahci1157test # [ 2.209206] scsi host1: ahci1158second # [ 2.029485] systemd-fsck[104]: nixos: clean, 12/65536 files, 13019/262144 blocks1159test # [ 2.212564] scsi host2: ahci1160test # [ 2.214062] usb usb4: Product: UHCI Host Controller1161test # [ 2.214987] usb usb4: Manufacturer: Linux 6.18.54 uhci_hcd1162test # [ 2.216457] scsi host3: ahci1163second # [ 2.035742] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1164test # [ 2.218020] usb usb4: SerialNumber: 0000:00:1d.21165second # [ 2.217611] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181166test # [ 2.219189] scsi host4: ahci1167second # [ 2.218990] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11168test # [ 2.220691] scsi host5: ahci1169test # [ 2.221798] hub 4-0:1.0: USB hub found1170test # [ 2.222550] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11171second # [ 2.223563] usb usb4: Product: UHCI Host Controller1172test # [ 2.224361] hub 4-0:1.0: 2 ports detected1173second # [ 2.224364] usb usb4: Manufacturer: Linux 6.18.54 uhci_hcd1174second # [ 2.226509] usb usb4: SerialNumber: 0000:00:1d.21175test # [ 2.229152] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11176second # [ 2.229734] hub 4-0:1.0: USB hub found1177second # [ 2.233292] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1178test # [ 2.234890] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11179second # [ 2.234961] hub 4-0:1.0: 2 ports detected1180test # [ 2.240263] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11181second # [ 2.240372] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1182test # [ 2.245670] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11183test # [ 2.246965] usb 1-1: new high-speed USB device number 2 using ehci-pci1184test # [ 2.248800] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11185second # [ 2.249045] ahci 0000:00:1f.2: flags: 64bit ncq only1186second # [ 2.261762] scsi host0: ahci1187second # [ 2.262835] scsi host1: ahci1188second # [ 2.264563] scsi host2: ahci1189second # [ 2.267734] scsi host3: ahci1190second # [ 2.271168] scsi host4: ahci1191second # [ 2.273837] scsi host5: ahci1192second # [ 2.275897] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11193second # [ 2.277176] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11194second # [ 2.280208] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11195second # [ 2.281609] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11196second # [ 2.282921] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11197second # [ 2.284275] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11198second # [ 2.350506] usb 2-1: new high-speed USB device number 2 using ehci-pci1199test # [ 2.377106] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001200test # [ 2.378423] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101201test # [ 2.379900] usb 1-1: Product: QEMU USB Tablet1202test # [ 2.380769] usb 1-1: Manufacturer: QEMU1203test # [ 2.381545] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11204test # [ 2.395966] hid: raw HID events driver (C) Jiri Kosina1205second # [ 2.480377] usb 2-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001206second # [ 2.481704] usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101207second # [ 2.483168] usb 2-1: Product: QEMU USB Tablet1208second # [ 2.484022] usb 2-1: Manufacturer: QEMU1209second # [ 2.484781] usb 2-1: SerialNumber: 28754-0000:00:1d.7-11210second # [ 2.499391] hid: raw HID events driver (C) Jiri Kosina1211test # [ 2.360579] systemd[1]: Mounting /sysroot...1212second # [ 2.359546] systemd[1]: Mounting /sysroot...1213test # [ 2.558798] ata5: SATA link down (SStatus 0 SControl 300)1214test # [ 2.560157] ata4: SATA link down (SStatus 0 SControl 300)1215test # [ 2.561259] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1216test # [ 2.562315] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001217test # [ 2.563159] ata3.00: applying bridge limits1218test # [ 2.564331] ata3.00: configured for UDMA/1001219test # [ 2.565420] ata1: SATA link down (SStatus 0 SControl 300)1220test # [ 2.566466] ata2: SATA link down (SStatus 0 SControl 300)1221test # [ 2.567846] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51222test # [ 2.569956] ata6: SATA link down (SStatus 0 SControl 300)1223second # [ 2.597769] ata2: SATA link down (SStatus 0 SControl 300)1224second # [ 2.598959] ata5: SATA link down (SStatus 0 SControl 300)1225second # [ 2.600212] ata1: SATA link down (SStatus 0 SControl 300)1226second # [ 2.601280] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1227second # [ 2.602336] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001228second # [ 2.603514] ata3.00: applying bridge limits1229second # [ 2.604549] ata6: SATA link down (SStatus 0 SControl 300)1230second # [ 2.605722] ata4: SATA link down (SStatus 0 SControl 300)1231second # [ 2.606707] ata3.00: configured for UDMA/1001232second # [ 2.607924] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51233test # [ 2.622359] usbcore: registered new interface driver usbhid1234test # [ 2.623212] usbhid: USB HID core driver1235test # [ 2.642425] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input21236test # [ 2.644967] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01237test # [ 2.650506] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1238test # [ 2.653539] EXT4-fs (vda): mounted filesystem 3660870a-a02f-4bcd-a02b-d39094ca8a24 r/w with ordered data mode. Quota mode: none.1239test # [ 2.488201] systemd[1]: Mounted /sysroot.1240test # [ 2.490153] systemd[1]: Reached target Initrd Root File System.1241second # [ 2.661934] usbcore: registered new interface driver usbhid1242test # [ 2.664366] cdrom: Uniform CD-ROM driver Revision: 3.201243test # [ 2.493767] systemd[1]: Starting Mountpoints Configured in the Real Root...1244second # [ 2.668464] usbhid: USB HID core driver1245second # [ 2.681892] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.0/0003:0627:0001.0001/input/input21246test # [ 2.511747] systemd-sysroot-fstab-check[137]: /sysroot should be mounted in the initrd, will request daemon-reload.1247second # [ 2.684720] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1248second # [ 2.686128] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01249test # [ 2.515085] systemd[1]: Reload requested from client PID 137 ('systemd-sysroot') (unit initrd-parse-etc.service)...1250test # [ 2.517082] systemd[1]: Reloading...1251second # [ 2.693488] EXT4-fs (vda): mounted filesystem c473103b-a00f-42f3-b58e-672480169577 r/w with ordered data mode. Quota mode: none.1252second # [ 2.696575] cdrom: Uniform CD-ROM driver Revision: 3.201253second # [ 2.519586] systemd[1]: Mounted /sysroot.1254second # [ 2.522251] systemd[1]: Reached target Initrd Root File System.1255second # [ 2.526072] systemd[1]: Starting Mountpoints Configured in the Real Root...1256second # [ 2.540931] systemd-sysroot-fstab-check[137]: /sysroot should be mounted in the initrd, will request daemon-reload.1257second # [ 2.545222] systemd[1]: Reload requested from client PID 137 ('systemd-sysroot') (unit initrd-parse-etc.service)...1258second # [ 2.548268] systemd[1]: Reloading...1259test # [ 2.580166] systemd[1]: Reloading finished in 62 ms.1260test # [ 2.591556] systemd-sysroot-fstab-check[137]: Requesting initrd-fs.target/start/replace...1261test # [ 2.595156] systemd-sysroot-fstab-check[137]: Requesting swap.target/start/replace...1262test # [ 2.600621] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1263test # [ 2.602792] systemd[1]: Finished Mountpoints Configured in the Real Root.1264test # [ 2.603984] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1265second # [ 2.613175] systemd[1]: Reloading finished in 65 ms.1266second # [ 2.621570] systemd-sysroot-fstab-check[137]: Requesting initrd-fs.target/start/replace...1267second # [ 2.624222] systemd-sysroot-fstab-check[137]: Requesting swap.target/start/replace...1268second # [ 2.628928] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1269second # [ 2.630635] systemd[1]: Finished Mountpoints Configured in the Real Root.1270second # [ 2.631872] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1271test # [ 3.361638] systemd[1]: Mounting /sysroot/nix/.ro-store...1272test # [ 3.364626] systemd[1]: Mounting /sysroot/nix/.rw-store...1273test # [ 3.369277] systemd[1]: Mounting /sysroot/run...1274second # [ 3.360885] systemd[1]: Mounting /sysroot/nix/.ro-store...1275second # [ 3.364135] systemd[1]: Mounting /sysroot/nix/.rw-store...1276second # [ 3.366146] systemd[1]: Mounting /sysroot/run...1277test # [ 3.376151] systemd[1]: Mounting /sysroot/tmp/shared...1278test # [ 3.378080] systemd[1]: Mounting /sysroot/tmp/xchg...1279second # [ 3.371134] systemd[1]: Mounting /sysroot/tmp/shared...1280second # [ 3.376067] systemd[1]: Mounting /sysroot/tmp/xchg...1281test # [ 3.392040] systemd[1]: Mounted /sysroot/nix/.ro-store.1282test # [ 3.394300] systemd[1]: Mounted /sysroot/nix/.rw-store.1283test # [ 3.397293] systemd[1]: Mounted /sysroot/run.1284second # [ 3.390866] systemd[1]: Mounted /sysroot/nix/.rw-store.1285test # [ 3.402084] systemd[1]: Mounted /sysroot/tmp/shared.1286second # [ 3.393657] systemd[1]: Mounted /sysroot/nix/.ro-store.1287test # [ 3.404575] systemd[1]: Mounted /sysroot/tmp/xchg.1288second # [ 3.398675] systemd[1]: Mounted /sysroot/run.1289test # [ 3.407326] systemd[1]: Starting rw-sysroot-nix-store.service...1290second # [ 3.400787] systemd[1]: Mounted /sysroot/tmp/shared.1291second # [ 3.401933] systemd[1]: Mounted /sysroot/tmp/xchg.1292second # [ 3.406118] systemd[1]: Starting rw-sysroot-nix-store.service...1293test # [ 3.416221] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1294test # [ 3.418084] systemd[1]: Finished rw-sysroot-nix-store.service.1295second # [ 3.415564] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1296second # [ 3.417098] systemd[1]: Finished rw-sysroot-nix-store.service.1297test # [ 4.361922] systemd[1]: Mounting /sysroot/nix/store...1298second # [ 4.360710] systemd[1]: Mounting /sysroot/nix/store...1299test # [ 4.382152] systemd[1]: Mounted /sysroot/nix/store.1300test # [ 4.383890] systemd[1]: Reached target Initrd File Systems.1301test # [ 4.386166] systemd[1]: Starting Find NixOS closure...1302second # [ 4.379902] systemd[1]: Mounted /sysroot/nix/store.1303test # [ 4.390136] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1304second # [ 4.382092] systemd[1]: Reached target Initrd File Systems.1305second # [ 4.383536] systemd[1]: Starting Find NixOS closure...1306second # [ 4.387188] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1307test # [ 4.406702] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1308test # [ 4.409435] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1309second # [ 4.405955] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1310test # [ 4.416857] systemd[1]: Finished Find NixOS closure.1311test # [ 4.418437] systemd[1]: Reached target Initrd Default Target.1312second # [ 4.409747] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1313test # [ 4.420630] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1314second # [ 4.414945] systemd[1]: Finished Find NixOS closure.1315second # [ 4.416838] systemd[1]: Reached target Initrd Default Target.1316second # [ 4.419408] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1317test # [ 4.434645] systemd[1]: Stopped target Initrd Default Target.1318test # [ 4.436346] systemd[1]: Stopped target Basic System.1319test # [ 4.438170] systemd[1]: Stopped target Initrd Root Device.1320test # [ 4.439388] systemd[1]: Stopped target Path Units.1321test # [ 4.440442] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1322test # [ 4.442400] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1323second # [ 4.435035] systemd[1]: Stopped target Initrd Default Target.1324test # [ 4.445174] systemd[1]: Stopped target Slice Units.1325second # [ 4.437034] systemd[1]: Stopped target Basic System.1326test # [ 4.446253] systemd[1]: Stopped target Socket Units.1327test # [ 4.447233] systemd[1]: Stopped target System Initialization.1328second # [ 4.438445] systemd[1]: Stopped target Initrd Root Device.1329test # [ 4.448308] systemd[1]: Stopped target Swaps.1330test # [ 4.449176] systemd[1]: Stopped target Timer Units.1331second # [ 4.440231] systemd[1]: Stopped target Path Units.1332test # [ 4.450325] systemd[1]: dbus.socket: Deactivated successfully.1333second # [ 4.441306] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1334second # [ 4.443331] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1335test # [ 4.451870] systemd[1]: Closed D-Bus System Message Bus Socket.1336test # [ 4.453325] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1337second # [ 4.445271] systemd[1]: Stopped target Slice Units.1338test # [ 4.455166] systemd[1]: Stopped Find NixOS closure.1339second # [ 4.447292] systemd[1]: Stopped target Socket Units.1340second # [ 4.448309] systemd[1]: Stopped target System Initialization.1341test # [ 4.457160] systemd[1]: Starting rw-sysroot-nix-store.service...1342second # [ 4.449515] systemd[1]: Stopped target Swaps.1343test # [ 4.458408] systemd[1]: systemd-sysctl.service: Deactivated successfully.1344second # [ 4.450794] systemd[1]: Stopped target Timer Units.1345test # [ 4.459892] systemd[1]: Stopped Apply Kernel Variables.1346second # [ 4.452225] systemd[1]: dbus.socket: Deactivated successfully.1347test # [ 4.461516] systemd[1]: systemd-modules-load.service: Deactivated successfully.1348second # [ 4.454165] systemd[1]: Closed D-Bus System Message Bus Socket.1349test # [ 4.463245] systemd[1]: Stopped Load Kernel Modules.1350second # [ 4.455414] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1351test # [ 4.465200] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1352second # [ 4.456917] systemd[1]: Stopped Find NixOS closure.1353test # [ 4.466885] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1354second # [ 4.458397] systemd[1]: Starting rw-sysroot-nix-store.service...1355test # [ 4.468469] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1356second # [ 4.460191] systemd[1]: systemd-sysctl.service: Deactivated successfully.1357second # [ 4.461653] systemd[1]: Stopped Apply Kernel Variables.1358test # [ 4.471134] systemd[1]: Stopped Create System Files and Directories.1359second # [ 4.463183] systemd[1]: systemd-modules-load.service: Deactivated successfully.1360test # [ 4.472412] systemd[1]: Stopped target Local File Systems.1361test # [ 4.473571] systemd[1]: Stopped target Preparation for Local File Systems.1362second # [ 4.465219] systemd[1]: Stopped Load Kernel Modules.1363test # [ 4.474938] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1364second # [ 4.466640] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1365test # [ 4.476435] systemd[1]: Stopped Coldplug All udev Devices.1366second # [ 4.469151] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1367test # [ 4.479547] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1368second # [ 4.470690] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1369test # [ 4.480894] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1370second # [ 4.472322] systemd[1]: Stopped Create System Files and Directories.1371test # [ 4.482264] systemd[1]: Stopped Virtual Console Setup.1372second # [ 4.473619] systemd[1]: Stopped target Local File Systems.1373second # [ 4.475181] systemd[1]: Stopped target Preparation for Local File Systems.1374second # [ 4.477167] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1375second # [ 4.478609] systemd[1]: Stopped Coldplug All udev Devices.1376second # [ 4.481162] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1377test # [ 4.492403] systemd[1]: initrd-cleanup.service: Deactivated successfully.1378second # [ 4.483545] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1379second # [ 4.484984] systemd[1]: Stopped Virtual Console Setup.1380test # [ 4.496172] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1381test # [ 4.499105] systemd[1]: systemd-udevd.service: Deactivated successfully.1382test # [ 4.502184] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1383second # [ 4.497161] systemd[1]: initrd-cleanup.service: Deactivated successfully.1384test # [ 4.507099] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1385test # [ 4.509494] systemd[1]: Finished rw-sysroot-nix-store.service.1386second # [ 4.501427] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1387test # [ 4.511427] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1388second # [ 4.502818] systemd[1]: systemd-udevd.service: Deactivated successfully.1389test # [ 4.514083] systemd[1]: Closed udev Control Socket.1390second # [ 4.506078] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1391test # [ 4.515595] systemd[1]: Starting Cleanup udev Database...1392test # [ 4.517180] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1393test # [ 4.518583] systemd[1]: Stopped Create Static Device Nodes in /dev.1394test # [ 4.520158] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1395second # [ 4.512334] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1396test # [ 4.521599] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1397second # [ 4.514516] systemd[1]: Finished rw-sysroot-nix-store.service.1398test # [ 4.524142] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1399test # [ 4.525388] systemd[1]: Stopped Create List of Static Device Nodes.1400second # [ 4.516586] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1401second # [ 4.518437] systemd[1]: Closed udev Control Socket.1402second # [ 4.520519] systemd[1]: Starting Cleanup udev Database...1403second # [ 4.522204] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1404second # [ 4.523799] systemd[1]: Stopped Create Static Device Nodes in /dev.1405second # [ 4.526188] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1406second # [ 4.527823] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1407test # [ 4.538514] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1408second # [ 4.529292] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1409test # [ 4.540688] systemd[1]: Finished Cleanup udev Database.1410second # [ 4.532375] systemd[1]: Stopped Create List of Static Device Nodes.1411test # [ 4.542450] systemd[1]: Reached target Switch Root.1412test # [ 4.544631] systemd[1]: Starting NixOS Activation...1413second # [ 4.549440] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1414second # [ 4.552224] systemd[1]: Finished Cleanup udev Database.1415second # [ 4.553405] systemd[1]: Reached target Switch Root.1416second # [ 4.556110] systemd[1]: Starting NixOS Activation...1417test # [ 4.598579] initrd-nixos-activation-start[189]: booting system configuration /nix/store/2dnj4v4pbn4gkq66j0l85n4jkfwcnxaw-nixos-system-test-test1418second # [ 4.609404] initrd-nixos-activation-start[189]: booting system configuration /nix/store/l8f9z309rr1m6j8y480gr50fzkvrzwkk-nixos-system-second-test1419test # [ 4.621757] initrd-nixos-activation-start[189]: running activation script...1420second # [ 4.634979] initrd-nixos-activation-start[189]: running activation script...1421test # [ 4.780773] initrd-nixos-activation-start[212]: setting up /etc...1422second # [ 4.797181] initrd-nixos-activation-start[212]: setting up /etc...1423test # [ 4.855329] initrd-nixos-activation-start[212]: setting up age secrets...1424second # [ 4.872516] initrd-nixos-activation-start[212]: setting up age secrets...1425second # [ 4.945826] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1426second # [ 4.947815] systemd[1]: Finished NixOS Activation.1427test # [ 4.957686] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1428second # [ 4.950124] systemd[1]: Starting Switch Root...1429test # [ 4.960105] systemd[1]: Finished NixOS Activation.1430test # [ 4.961401] systemd[1]: Starting Switch Root...1431second # [ 4.961758] systemd[1]: Switching root.1432test # [ 4.972461] systemd[1]: Switching root.1433test # [ 5.265866] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).1434second # [ 5.266855] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).1435test # [ 5.342567] NET: Registered PF_VSOCK protocol family1436second # [ 5.347337] NET: Registered PF_VSOCK protocol family1437test # [ 5.696133] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1438test # [ 5.700975] systemd[1]: Detected virtualization kvm.1439test # [ 5.701909] systemd[1]: Detected architecture x86-64.1440test # [ 5.702902] systemd[1]: Detected first boot.1441test # [ 5.704832] systemd[1]: Initializing machine ID from random generator.1442second # [ 5.700947] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1443second # [ 5.705793] systemd[1]: Detected virtualization kvm.1444second # [ 5.706665] systemd[1]: Detected architecture x86-64.1445second # [ 5.707626] systemd[1]: Detected first boot.1446second # [ 5.709275] systemd[1]: Initializing machine ID from random generator.1447test # [ 5.788841] systemd[1]: bpf-restrict-fs: LSM BPF program attached1448second # [ 5.791209] systemd[1]: bpf-restrict-fs: LSM BPF program attached1449test # [ 5.863477] systemd[1]: Applying preset policy.1450second # [ 5.866549] systemd[1]: Applying preset policy.1451test # [ 6.037903] systemd[1]: Populated /etc with preset unit settings.1452second # [ 6.049017] systemd[1]: Populated /etc with preset unit settings.1453test # [ 6.233605] systemd[1]: initrd-switch-root.service: Deactivated successfully.1454test # [ 6.235323] systemd[1]: Stopped initrd-switch-root.service.1455second # [ 6.235989] systemd[1]: initrd-switch-root.service: Deactivated successfully.1456test # [ 6.237642] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1457second # [ 6.237710] systemd[1]: Stopped initrd-switch-root.service.1458test # [ 6.239886] systemd[1]: Created slice Slice /system/getty.1459second # [ 6.240254] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1460test # [ 6.241455] systemd[1]: Created slice User and Session Slice.1461test # [ 6.242557] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1462second # [ 6.242751] systemd[1]: Created slice Slice /system/getty.1463test # [ 6.244011] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1464second # [ 6.244214] systemd[1]: Created slice User and Session Slice.1465test # [ 6.245370] systemd[1]: Expecting device /dev/hvc0...1466test # [ 6.246278] systemd[1]: Expecting device /dev/ttyS0...1467second # [ 6.245344] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1468test # [ 6.247251] systemd[1]: Reached target Local Encrypted Volumes.1469second # [ 6.246797] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1470test # [ 6.248307] systemd[1]: Stopped target initrd-fs.target.1471second # [ 6.248119] systemd[1]: Expecting device /dev/hvc0...1472second # [ 6.249021] systemd[1]: Expecting device /dev/ttyS0...1473test # [ 6.249251] systemd[1]: Stopped target initrd-root-fs.target.1474test # [ 6.258852] systemd[1]: Stopped target initrd-switch-root.target.1475second # [ 6.249985] systemd[1]: Reached target Local Encrypted Volumes.1476test # [ 6.259952] systemd[1]: Reached target Virtual Machines and Containers.1477second # [ 6.259604] systemd[1]: Stopped target initrd-fs.target.1478test # [ 6.261125] systemd[1]: Reached target Path Units.1479second # [ 6.260561] systemd[1]: Stopped target initrd-root-fs.target.1480test # [ 6.262049] systemd[1]: Reached target Remote File Systems.1481second # [ 6.261572] systemd[1]: Stopped target initrd-switch-root.target.1482test # [ 6.263077] systemd[1]: Reached target Slice Units.1483second # [ 6.262666] systemd[1]: Reached target Virtual Machines and Containers.1484test # [ 6.264030] systemd[1]: Reached target Swaps.1485second # [ 6.263800] systemd[1]: Reached target Path Units.1486second # [ 6.264721] systemd[1]: Reached target Remote File Systems.1487second # [ 6.265733] systemd[1]: Reached target Slice Units.1488test # [ 6.265805] systemd[1]: Listening on Query the User Interactively for a Password.1489second # [ 6.266657] systemd[1]: Reached target Swaps.1490test # [ 6.268653] systemd[1]: Listening on Process Core Dump Socket.1491second # [ 6.268633] systemd[1]: Listening on Query the User Interactively for a Password.1492test # [ 6.270694] systemd[1]: Listening on Credential Encryption/Decryption.1493second # [ 6.271175] systemd[1]: Listening on Process Core Dump Socket.1494test # [ 6.272659] systemd[1]: Listening on Factory Reset Management.1495second # [ 6.273063] systemd[1]: Listening on Credential Encryption/Decryption.1496test # [ 6.273820] systemd[1]: Listening on Hostname Service Socket.1497second # [ 6.275050] systemd[1]: Listening on Factory Reset Management.1498test # [ 6.276665] systemd[1]: Starting Journal Log Access Socket...1499second # [ 6.276221] systemd[1]: Listening on Hostname Service Socket.1500test # [ 6.278066] systemd[1]: Listening on Journal Audit Socket.1501second # [ 6.279056] systemd[1]: Starting Journal Log Access Socket...1502test # [ 6.280625] systemd[1]: Listening on Console Output Muting Service Socket.1503second # [ 6.280585] systemd[1]: Listening on Journal Audit Socket.1504test # [ 6.281952] systemd[1]: Listening on Network Management Metrics Varlink Socket.1505test # [ 6.283446] systemd[1]: Listening on Network Management Varlink Socket.1506second # [ 6.283132] systemd[1]: Listening on Console Output Muting Service Socket.1507test # [ 6.284727] systemd[1]: Listening on Network Management Netlink Socket.1508second # [ 6.284517] systemd[1]: Listening on Network Management Metrics Varlink Socket.1509test # [ 6.286030] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1510second # [ 6.285963] systemd[1]: Listening on Network Management Varlink Socket.1511test # [ 6.287389] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1512second # [ 6.287257] systemd[1]: Listening on Network Management Netlink Socket.1513second # [ 6.288599] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1514test # [ 6.288989] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1515second # [ 6.289953] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1516second # [ 6.291522] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1517test # [ 6.292916] systemd[1]: Listening on Disk Repartitioning Service Socket.1518test # [ 6.294233] systemd[1]: Listening on Resolve Monitor Varlink Socket.1519test # [ 6.295443] systemd[1]: Listening on Resolve Service Varlink Socket.1520second # [ 6.295528] systemd[1]: Listening on Disk Repartitioning Service Socket.1521test # [ 6.296649] systemd[1]: Listening on udev Control Socket.1522second # [ 6.296797] systemd[1]: Listening on Resolve Monitor Varlink Socket.1523test # [ 6.297754] systemd[1]: Listening on udev Varlink Socket.1524second # [ 6.298000] systemd[1]: Listening on Resolve Service Varlink Socket.1525test # [ 6.299831] systemd[1]: Mounting Huge Pages File System...1526second # [ 6.299204] systemd[1]: Listening on udev Control Socket.1527second # [ 6.300293] systemd[1]: Listening on udev Varlink Socket.1528second # [ 6.302647] systemd[1]: Mounting Huge Pages File System...1529test # [ 6.303621] systemd[1]: Mounting POSIX Message Queue File System...1530second # [ 6.305510] systemd[1]: Mounting POSIX Message Queue File System...1531test # [ 6.310238] systemd[1]: Mounting Kernel Debug File System...1532second # [ 6.314456] systemd[1]: Mounting Kernel Debug File System...1533test # [ 6.317889] systemd[1]: Mounting Kernel Trace File System...1534second # [ 6.322631] systemd[1]: Mounting Kernel Trace File System...1535test # [ 6.327278] systemd[1]: Starting Create List of Static Device Nodes...1536second # [ 6.333683] systemd[1]: Starting Create List of Static Device Nodes...1537test # [ 6.339846] systemd[1]: Starting Load Kernel Module configfs...1538second # [ 6.347550] systemd[1]: Starting Load Kernel Module configfs...1539test # [ 6.347739] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1540test # [ 6.351468] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1541second # [ 6.352650] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1542test # [ 6.357244] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1543second # [ 6.358882] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1544test # [ 6.361615] systemd[1]: Mounting FUSE Control File System...1545second # [ 6.361735] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1546test # [ 6.362814] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671547second # [ 6.368036] systemd[1]: Mounting FUSE Control File System...1548test # [ 6.371601] systemd[1]: Starting Journal Service...1549second # [ 6.370488] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671550test # [ 6.380378] systemd[1]: Starting Load Kernel Modules...1551second # [ 6.381713] systemd[1]: Starting Journal Service...1552test # [ 6.389919] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1553second # [ 6.391763] systemd[1]: Starting Load Kernel Modules...1554test # [ 6.394575] systemd[1]: Starting Remount Root and Kernel File Systems...1555second # [ 6.399630] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1556test # [ 6.401960] systemd-journald[336]: Collecting audit messages is enabled.1557test # [ 6.403147] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1558second # [ 6.411567] systemd[1]: Starting Remount Root and Kernel File Systems...1559test # [ 6.415620] systemd[1]: Starting Coldplug All udev Devices...1560second # [ 6.415586] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1561test # [ 6.426083] systemd[1]: Listening on Journal Log Access Socket.1562second # [ 6.426965] systemd-journald[316]: Collecting audit messages is enabled.1563test # [ 6.258885] systemd[1]: Queued start job for default target Multi-User System.1564second # [ 6.433947] systemd[1]: Starting Coldplug All udev Devices...1565test # [ 6.434033] systemd[1]: Started Journal Service.1566test # [ 6.262739] systemd[1]: systemd-journald.service: Deactivated successfully.1567test # [ 6.267862] systemd[1]: Mounted Huge Pages File System.1568test # [ 6.269397] systemd[1]: Mounted POSIX Message Queue File System.1569test # [ 6.272274] systemd[1]: Mounted Kernel Debug File System.1570test # [ 6.274312] systemd[1]: Mounted Kernel Trace File System.1571second # [ 6.446630] systemd[1]: Listening on Journal Log Access Socket.1572test # [ 6.276582] systemd[1]: Finished Create List of Static Device Nodes.1573test # [ 6.277837] systemd[1]: modprobe@configfs.service: Deactivated successfully.1574second # [ 6.451368] systemd[1]: Mounted Huge Pages File System.1575test # [ 6.283149] systemd[1]: Finished Load Kernel Module configfs.1576second # [ 6.456097] systemd[1]: Mounted POSIX Message Queue File System.1577test # [ 6.293499] systemd-modules-load[338]: Module 'ctr' is built in1578second # [ 6.283437] systemd[1]: Queued start job for default target Multi-User System.1579second # [ 6.286507] systemd[1]: systemd-journald.service: Deactivated successfully.1580second # [ 6.468638] systemd[1]: Started Journal Service.1581second # [ 6.297497] systemd-modules-load[317]: Module 'ctr' is built in1582test # [ 6.482928] EXT4-fs (vda): re-mounted 3660870a-a02f-4bcd-a02b-d39094ca8a24.1583test # [ 6.485452] loop: module loaded1584second # [ 6.304800] systemd[1]: Mounted Kernel Debug File System.1585test # [ 6.316136] systemd-modules-load[338]: Inserted module 'loop'1586second # [ 6.311313] systemd[1]: Mounted Kernel Trace File System.1587test # [ 6.322163] systemd[1]: Mounting Kernel Configuration File System...1588test # [ 6.326109] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1589second # [ 6.498615] loop: module loaded1590second # [ 6.320340] systemd-modules-load[317]: Inserted module 'loop'1591test # [ 6.329928] systemd[1]: Mounted FUSE Control File System.1592second # [ 6.321623] systemd[1]: Finished Create List of Static Device Nodes.1593second # [ 6.322819] systemd[1]: modprobe@configfs.service: Deactivated successfully.1594second # [ 6.504893] EXT4-fs (vda): re-mounted c473103b-a00f-42f3-b58e-672480169577.1595second # [ 6.329272] systemd[1]: Finished Load Kernel Module configfs.1596test # [ 6.339359] systemd[1]: Finished Load Kernel Modules.1597second # [ 6.332977] systemd[1]: Mounted FUSE Control File System.1598test # [ 6.344261] systemd[1]: Finished Remount Root and Kernel File Systems.1599second # [ 6.340662] systemd[1]: Finished Load Kernel Modules.1600test # [ 6.350461] systemd-oomd[339]: No swap; memory pressure usage will be degraded1601second # [ 6.341962] systemd[1]: Finished Remount Root and Kernel File Systems.1602test # [ 6.354338] systemd[1]: Listening on Disk Image Download Service Socket.1603second # [ 6.352612] systemd[1]: Listening on Disk Image Download Service Socket.1604test # [ 6.366872] systemd[1]: Starting Firewall...1605second # [ 6.358288] systemd-oomd[319]: No swap; memory pressure usage will be degraded1606second # [ 6.362115] systemd[1]: Mounting Kernel Configuration File System...1607test # [ 6.373131] systemd[1]: Starting Flush Journal to Persistent Storage...1608second # [ 6.368118] systemd[1]: Starting Firewall...1609test # [ 6.377220] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1610second # [ 6.377714] systemd[1]: Starting Flush Journal to Persistent Storage...1611test # [ 6.387156] systemd[1]: Starting Load/Save OS Random Seed...1612second # [ 6.379063] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1613test # [ 6.397171] systemd[1]: Starting Apply Kernel Variables...1614second # [ 6.389129] systemd[1]: Starting Load/Save OS Random Seed...1615test # [ 6.398435] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1616test # [ 6.411150] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1617second # [ 6.406171] systemd[1]: Starting Apply Kernel Variables...1618second # [ 6.417797] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1619second # [ 6.419323] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1620second # [ 6.422208] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1621second # [ 6.628068] systemd-journald[316]: Received client request to flush runtime journal.1622test # [ 6.629588] systemd-journald[336]: Received client request to flush runtime journal.1623second # [ 6.652778] systemd[1]: Finished Load/Save OS Random Seed.1624second # [ 6.657243] systemd[1]: Reached target First Boot Complete.1625second # [ 6.659355] systemd[1]: Mounted Kernel Configuration File System.1626test # [ 6.669531] systemd[1]: Finished Load/Save OS Random Seed.1627second # [ 6.664454] systemd[1]: Finished Apply Kernel Variables.1628second # [ 6.665666] systemd[1]: Starting Network Name Resolution...1629test # [ 6.675152] systemd[1]: Mounted Kernel Configuration File System.1630second # [ 6.668390] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1631test # [ 6.678592] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1632second # [ 6.670253] systemd[1]: Starting Create Static Device Nodes in /dev...1633test # [ 6.681160] systemd[1]: Reached target First Boot Complete.1634test # [ 6.683609] systemd[1]: Starting Create Static Device Nodes in /dev...1635second # [ 6.673261] systemd[1]: Finished Flush Journal to Persistent Storage.1636test # [ 6.685127] systemd[1]: Finished Apply Kernel Variables.1637test # [ 6.687171] systemd[1]: Starting Network Name Resolution...1638test # [ 6.690087] systemd[1]: Finished Create Static Device Nodes in /dev.1639test # [ 6.691299] systemd[1]: Reached target Preparation for Local File Systems.1640test # [ 6.692526] systemd[1]: Starting Rule-based Manager for Device Events and Files...1641test # [ 6.697125] systemd[1]: Finished Flush Journal to Persistent Storage.1642second # [ 6.690427] systemd-resolved[345]: Positive Trust Anchors:1643second # [ 6.692086] systemd-resolved[345]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1644second # [ 6.695140] systemd-resolved[345]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161645second # [ 6.696893] systemd-resolved[345]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test1646second # [ 6.728146] systemd[1]: Finished Create Static Device Nodes in /dev.1647second # [ 6.729431] systemd[1]: Reached target Preparation for Local File Systems.1648test # [ 6.738930] systemd-resolved[370]: Positive Trust Anchors:1649test # [ 6.741309] systemd-resolved[370]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1650second # [ 6.734279] systemd[1]: Starting Rule-based Manager for Device Events and Files...1651test # [ 6.743720] systemd-resolved[370]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161652test # [ 6.747228] systemd-resolved[370]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test1653second # [ 6.750562] systemd[1]: Finished Coldplug All udev Devices.1654test # [ 6.774392] systemd[1]: Finished Coldplug All udev Devices.1655second # [ 6.768948] systemd-resolved[345]: Using system hostname 'second'.1656second # [ 6.774683] systemd[1]: Started Network Name Resolution.1657test # [ 6.784491] systemd-udevd[372]: Using default interface naming scheme 'v261'.1658second # [ 6.794777] systemd-udevd[360]: Using default interface naming scheme 'v261'.1659test # [ 6.811584] systemd-resolved[370]: Using system hostname 'test'.1660test # [ 6.815910] systemd[1]: Started Network Name Resolution.1661second # [ 6.848105] systemd[1]: Started Rule-based Manager for Device Events and Files.1662test # [ 6.858101] systemd[1]: Started Rule-based Manager for Device Events and Files.1663test # [ 6.986600] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1664second # [ 6.979848] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1665test # [ 7.065382] systemd[1]: Mounting /run/wrappers...1666second # [ 7.060285] systemd[1]: Mounting /run/wrappers...1667second # [ 7.062780] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1668test # [ 7.081387] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1669test # [ 7.100655] systemd[1]: Mounted /run/wrappers.1670test # [ 7.101699] systemd[1]: Reached target Local File Systems.1671test # [ 7.104705] systemd[1]: Listening on Boot Loader Control Service Socket.1672second # [ 7.096788] systemd[1]: Mounted /run/wrappers.1673second # [ 7.098495] systemd[1]: Reached target Local File Systems.1674test # [ 7.109108] systemd[1]: Starting register-nix-paths.service...1675second # [ 7.102129] systemd[1]: Listening on Boot Loader Control Service Socket.1676test # [ 7.114572] systemd[1]: Starting Create SUID/SGID Wrappers...1677second # [ 7.106142] systemd[1]: Starting register-nix-paths.service...1678test # [ 7.116092] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1679second # [ 7.111621] systemd[1]: Starting Create SUID/SGID Wrappers...1680second # [ 7.112749] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1681second # [ 7.124579] systemd[1]: Starting Save Transient machine-id to Disk...1682test # [ 7.133503] systemd[1]: Starting Save Transient machine-id to Disk...1683test # [ 7.147434] systemd[1]: Starting Create System Files and Directories...1684second # [ 7.139130] systemd[1]: Starting Create System Files and Directories...1685test # [ 7.148727] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1686second # [ 7.143281] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1687second # [ 7.155287] (udev-worker)[392]: Network interface NamePolicy= disabled on kernel command line.1688test # [ 7.167321] (udev-worker)[406]: Network interface NamePolicy= disabled on kernel command line.1689second # [ 7.168543] (udev-worker)[387]: Network interface NamePolicy= disabled on kernel command line.1690test # [ 7.195258] (udev-worker)[409]: Network interface NamePolicy= disabled on kernel command line.1691second # [ 7.222716] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1692test # [ 7.238321] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1693second # [ 7.234285] systemd[1]: Finished Save Transient machine-id to Disk.1694test # [ 7.245406] systemd[1]: Finished Save Transient machine-id to Disk.1695test # [ 7.273099] systemd[1]: Finished Create System Files and Directories.1696second # [ 7.274074] systemd[1]: Finished Create System Files and Directories.1697second # [ 7.284468] systemd[1]: Starting Rebuild Journal Catalog...1698test # [ 7.306121] systemd[1]: Starting Rebuild Journal Catalog...1699test # [ 7.313258] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1700second # [ 7.304769] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1701second # [ 7.386976] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1702test # [ 7.406829] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1703test # [ 7.420482] systemd[1]: Finished Rebuild Journal Catalog.1704second # [ 7.413223] systemd[1]: Finished Rebuild Journal Catalog.1705second # [ 7.419138] systemd[1]: Starting Update is Completed...1706test # [ 7.430374] systemd[1]: Starting Update is Completed...1707second # [ 7.480103] systemd[1]: Finished Update is Completed.1708test # [ 7.489314] systemd[1]: Finished Update is Completed.1709test # [ 7.652585] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1710test # [ 7.655179] systemd[1]: Finished Create SUID/SGID Wrappers.1711second # [ 7.655362] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1712second # [ 7.658719] systemd[1]: Finished Create SUID/SGID Wrappers.1713second # [ 7.665285] systemd[1]: Finished Firewall.1714second # [ 7.668125] systemd[1]: Reached target Preparation for Network.1715second # [ 7.669254] systemd[1]: Listening on Network Management Resolve Hook Socket.1716test # [ 7.854953] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1717second # [ 7.674800] systemd[1]: Starting Network Management...1718second # [ 7.872882] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31719second # [ 7.875096] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1720test # [ 7.707968] systemd[1]: Finished register-nix-paths.service.1721test # [ 7.709805] systemd[1]: Reached target System Initialization.1722second # [ 7.881927] mousedev: PS/2 mouse device common for all mice1723test # [ 7.713133] systemd[1]: Started Discard unused filesystem blocks once a week.1724test # [ 7.714427] systemd[1]: Started Daily Cleanup of Temporary Directories.1725second # [ 7.707144] systemd[1]: Finished register-nix-paths.service.1726test # [ 7.716845] systemd[1]: Reached target Timer Units.1727second # [ 7.708221] systemd[1]: Reached target System Initialization.1728test # [ 7.718209] systemd[1]: Listening on D-Bus System Message Bus Socket.1729second # [ 7.711667] systemd[1]: Started Discard unused filesystem blocks once a week.1730test # [ 7.721292] systemd[1]: Listening on Nix Daemon Socket.1731second # [ 7.712903] systemd[1]: Started Daily Cleanup of Temporary Directories.1732test # [ 7.724125] systemd[1]: Listening on Authorization Manager Agent Helper.1733second # [ 7.716245] systemd[1]: Reached target Timer Units.1734test # [ 7.725305] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1735test # [ 7.726748] systemd[1]: Reached target Socket Units.1736second # [ 7.719830] systemd[1]: Listening on D-Bus System Message Bus Socket.1737test # [ 7.730473] systemd[1]: Starting D-Bus System Message Bus...1738second # [ 7.722669] systemd[1]: Listening on Nix Daemon Socket.1739second # [ 7.723672] systemd[1]: Listening on Authorization Manager Agent Helper.1740second # [ 7.724835] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1741second # [ 7.728082] systemd[1]: Reached target Socket Units.1742second # [ 7.729230] systemd[1]: Starting D-Bus System Message Bus...1743test # [ 7.787200] systemd[1]: Finished Firewall.1744test # [ 7.789434] systemd[1]: Reached target Preparation for Network.1745test # [ 7.791097] systemd[1]: Listening on Network Management Resolve Hook Socket.1746test # [ 7.796069] systemd[1]: Starting Network Management...1747test # [ 7.817497] dbus-broker-launch[555]: Looking up NSS user entry for 'systemd-timesync'...1748test # [ 7.823126] dbus-broker-launch[555]: NSS returned no entry for 'systemd-timesync'1749test # [ 7.824426] dbus-broker-launch[555]: Invalid user-name in /nix/store/5dbswa4mma49pnccdlcr143wqrw3vj68-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1750second # [ 7.937579] ACPI: button: Power Button [PWRF]1751second # [ 8.014470] Console: switching to colour dummy device 80x251752test # [ 7.844277] systemd-resolved[370]: Clock change detected. Flushing caches.1753test # [ 7.864567] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31754test # [ 7.870169] mousedev: PS/2 mouse device common for all mice1755test # [ 7.876107] ACPI: button: Power Button [PWRF]1756test # [ 7.884913] dbus-broker-launch[555]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/vxlcffmhw7wsis0ib871ppnb88l437is-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1757test # [ 7.985956] rtc_cmos PNP0B00:00: RTC can wake from S41758test # [ 8.015524] rtc_cmos PNP0B00:00: registered as rtc01759test # [ 7.890404] dbus-broker-launch[555]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/vxlcffmhw7wsis0ib871ppnb88l437is-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1760second # [ 7.884837] systemd-resolved[345]: Clock change detected. Flushing caches.1761test # [ 7.894603] dbus-broker-launch[555]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/4vj8rc4mpy3mjsnp8aajf0gycr1hfmlh-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1762test # [ 7.900643] dbus-broker-launch[555]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/d527hwwggm2wz18p4v8cr39amcpx3g4c-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1763second # [ 8.059602] rtc_cmos PNP0B00:00: RTC can wake from S41764test # [ 7.903763] systemd[1]: Started D-Bus System Message Bus.1765second # [ 8.061575] parport_pc 00:02: reported by Plug and Play ACPI1766second # [ 8.061663] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1767test # [ 7.905817] systemd[1]: Reached target Basic System.1768test # [ 7.910216] systemd[1]: Starting Network Manager Script Dispatcher Service...1769test # [ 7.915474] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1770second # [ 7.911054] dbus-broker-launch[543]: Looking up NSS user entry for 'systemd-timesync'...1771second # [ 8.063329] cfg80211: Loading compiled-in X.509 certificates for regulatory database1772second # [ 8.064769] rtc_cmos PNP0B00:00: registered as rtc01773second # [ 7.916533] dbus-broker-launch[543]: NSS returned no entry for 'systemd-timesync'1774second # [ 7.919986] dbus-broker-launch[543]: Invalid user-name in /nix/store/5dbswa4mma49pnccdlcr143wqrw3vj68-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1775test # [ 7.932464] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1776second # [ 8.064865] rtc_cmos PNP0B00:00: setting system clock to 2026-09-30T15:03:55 UTC (1790780635)1777second # [ 8.064947] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1778test # [ 7.942784] dbus-broker-launch[555]: Ready1779test # [ 7.947196] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1780second # [ 7.940204] dbus-broker-launch[543]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/vxlcffmhw7wsis0ib871ppnb88l437is-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1781second # [ 7.943876] dbus-broker-launch[543]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/vxlcffmhw7wsis0ib871ppnb88l437is-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1782test # [ 7.956153] systemd[1]: Starting Network Manager...1783test # [ 8.015619] rtc_cmos PNP0B00:00: setting system clock to 2026-09-30T15:03:55 UTC (1790780635)1784second # [ 7.948475] dbus-broker-launch[543]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/4vj8rc4mpy3mjsnp8aajf0gycr1hfmlh-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1785test # [ 8.015707] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1786second # [ 7.952459] dbus-broker-launch[543]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/d527hwwggm2wz18p4v8cr39amcpx3g4c-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1787second # [ 7.957340] systemd[1]: Started D-Bus System Message Bus.1788second # [ 7.959328] systemd[1]: Reached target Basic System.1789second # [ 7.963275] systemd-networkd[540]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1790test # [ 7.975192] systemd[1]: Started backdoor.service.1791second # [ 7.968643] systemd-networkd[540]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1792second # [ 7.971806] systemd-networkd[540]: lo: Link UP1793second # [ 7.973877] systemd-networkd[540]: lo: Gained carrier1794second # [ 7.975557] systemd[1]: Starting Network Manager Script Dispatcher Service...1795second # [ 7.980872] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1796test # [ 7.993228] systemd[1]: Starting Import lastlog data into lastlog2 database...1797second # [ 7.987083] systemd-networkd[540]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1798second # [ 7.992158] systemd-networkd[540]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1799second # [ 7.993699] systemd-networkd[540]: eth1: Link UP1800second # [ 7.994584] systemd-networkd[540]: eth1: Gained carrier1801test # [ 8.003292] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1802second # [ 7.995543] systemd-networkd[540]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1803second # [ 8.066355] systemd-journald[316]: Time jumped backwards, rotating.1804second # [ 8.080247] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1805second # [ 8.006135] systemd[1]: Starting Network Manager...1806second # [ 8.010357] dbus-broker-launch[543]: Ready1807second # [ 8.013232] systemd[1]: Started backdoor.service.1808test # [ 8.034476] systemd[1]: Starting Post-Boot Actions...1809second # [ 8.035196] systemd[1]: Starting Import lastlog data into lastlog2 database...1810test # [ 8.053253] 5y3ssc77g9a48j0jag4pj6wrwmh8mnj4-wifi-secrets[570]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1811second # [ 8.047160] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1812test # [ 8.017832] systemd-journald[336]: Time jumped backwards, rotating.1813test # [ 8.024635] parport_pc 00:02: reported by Plug and Play ACPI1814test # [ 8.071192] systemd[1]: Started Reset console on configuration changes.1815second # [ 8.085311] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input41816second # [ 8.106282] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1817test # [ 8.073571] 2fzr21av002hkddm9grsnsbb2wa3c1bn-wifi-secrets[572]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1818second # [ 8.070219] systemd[1]: Starting Post-Boot Actions...1819test # [ 8.093241] bjn7ymirp1h8ai1aki9b9jfc6bwb1c5s-wifi-secrets[573]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1820second # [ 8.092120] systemd[1]: Started Reset console on configuration changes.1821second # [ 8.107414] systemd[1]: Started Network Management.1822test # [ 8.120175] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1823second # [ 8.123427] 5y3ssc77g9a48j0jag4pj6wrwmh8mnj4-wifi-secrets[554]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1824test # [ 8.024722] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1825test # [ 8.140516] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1826test # [ 8.141852] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1827test # [ 8.143338] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1828test # [ 8.144568] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1829second # connecting to host...1830second # [ 8.144763] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1831test # [ 8.154954] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1832second # [ 8.106369] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1833second # [ 8.212259] NET: Registered PF_PACKET protocol family1834second # [ 8.337057] [drm] Found bochs VGA, ID 0xb0c5.1835second # [ 8.337059] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1836second # [ 8.159537] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1837second # [ 8.167178] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1838test # [ 8.186595] nsncd[578]: Sep 30 15:03:55.842 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1839test # [ 8.190541] systemd[1]: Started Name Service Cache Daemon (nsncd).1840test # connecting to host...1841test # [ 8.196326] systemd[1]: Finished Post-Boot Actions.1842second # [ 8.192850] nsncd[559]: Sep 30 15:03:55.808 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1843test # [ 8.033272] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input41844test # [ 8.036288] Console: switching to colour dummy device 80x251845second # [ 8.204096] systemd[1]: Finished Post-Boot Actions.1846second # [ 8.205113] systemd[1]: Started Name Service Cache Daemon (nsncd).1847test: Guest shell says: b'Spawning backdoor root shell...\n'1848test # [ 8.227337] systemd[1]: Started Network Manager Script Dispatcher Service.1849test: connected to guest root shell1850test # [ 8.234658] systemd[1]: Reached target Host and Network Name Lookups.1851second # [ 8.411278] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1852test: (connecting took 8.89 seconds)1853test # [ 8.235755] systemd[1]: Reached target User and Group Name Lookups.1854test: (finished: waiting for the VM to finish booting, in 8.89 seconds)1855second # [ 8.412081] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01856second # [ 8.234573] systemd[1]: Started Network Manager Script Dispatcher Service.1857second # [ 8.244321] systemd[1]: Reached target Host and Network Name Lookups.1858second # [ 8.245456] systemd[1]: Reached target User and Group Name Lookups.1859test # [ 8.103771] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1860second # [ 8.255161] systemd[1]: Starting User Login Management...1861second # [ 8.445645] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1862second # [ 8.448473] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1863test # [ 8.280501] systemd[1]: Starting User Login Management...1864second # [ 8.275569] systemd[1]: Finished Import lastlog data into lastlog2 database.1865test # [ 8.286535] systemd[1]: Finished Import lastlog data into lastlog2 database.1866test # [ 8.376749] cfg80211: Loading compiled-in X.509 certificates for regulatory database1867second # [ 8.288995] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1868second # [ 8.297750] systemd[1]: Starting Hostname Service...1869test # [ 8.314159] systemd-networkd[564]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1870test # [ 8.320384] systemd-networkd[564]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1871test # [ 8.323539] systemd-networkd[564]: lo: Link UP1872second # [ 8.495307] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61873test # [ 8.325664] systemd-networkd[564]: lo: Gained carrier1874second # [ 8.498307] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51875test # [ 8.416968] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1876test # [ 8.417054] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1877test # [ 8.336355] systemd-networkd[564]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1878test # [ 8.338800] systemd-networkd[564]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1879test # [ 8.344375] systemd[1]: Started Network Management.1880test # [ 8.354121] systemd-networkd[564]: eth1: Link UP1881test # [ 8.354970] systemd-networkd[564]: eth1: Gained carrier1882test # [ 8.356921] systemd-networkd[564]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1883test # [ 8.364464] systemd[1]: Starting Hostname Service...1884second # [ 8.359411] systemd-logind[596]: New seat seat0.1885test # [ 8.367914] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1886second # [ 8.364203] systemd[1]: Started User Login Management.1887second # [ 8.373670] systemd[1]: Starting linger-users.service...1888test # [ 8.455682] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1889test # [ 8.455796] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1890test # [ 8.544695] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61891second # [ 8.418186] systemd[1]: Started Hostname Service.1892second # [ 8.430529] systemd[1]: linger-users.service: Deactivated successfully.1893second # [ 8.434358] systemd[1]: Finished linger-users.service.1894test # [ 8.544962] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51895second # [ 8.444742] systemd[1]: Starting Virtual Console Setup...1896test # [ 8.578562] NET: Registered PF_PACKET protocol family1897test # [ 8.626918] [drm] Found bochs VGA, ID 0xb0c5.1898test # [ 8.626921] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1899test # [ 8.447278] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1900test # [ 8.459502] systemd-logind[629]: New seat seat0.1901test # [ 8.463388] systemd[1]: Started User Login Management.1902test # [ 8.471337] systemd[1]: Starting linger-users.service...1903test # [ 8.656628] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1904test # [ 8.657490] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01905test # [ 8.489748] systemd[1]: Starting Virtual Console Setup...1906second # [ 8.602977] Console: switching to colour frame buffer device 160x501907test # [ 8.516881] systemd[1]: Started Hostname Service.1908second # [ 8.689978] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1909second # [ 8.491085] systemd-networkd[540]: eth0: Link UP1910second # [ 8.513653] systemd-networkd[540]: eth0: Gained carrier1911second # [ 8.515404] systemd-logind[596]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1912second # [ 8.519686] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1913second # [ 8.521122] systemd[1]: Started Network Manager.1914test # [ 8.530084] systemd[1]: linger-users.service: Deactivated successfully.1915second # [ 8.523144] systemd[1]: Reached target Network.1916test # [ 8.533569] systemd[1]: Finished linger-users.service.1917second # [ 8.531076] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1918second # [ 8.536464] systemd[1]: Starting Permit User Sessions...1919second # [ 8.542492] systemd-logind[596]: Watching system buttons on /dev/input/event2 (Power Button)1920test # [ 8.582106] systemd-networkd[564]: eth0: Link UP1921test # [ 8.583605] systemd-networkd[564]: eth0: Gained carrier1922test # [ 8.588113] systemd-logind[629]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1923second # [ 8.594137] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1924test # [ 8.608662] systemd[1]: Started Network Manager.1925second # [ 8.601080] systemd[1]: Stopped Virtual Console Setup.1926test # [ 8.612209] systemd[1]: Reached target Network.1927second # [ 8.607349] systemd[1]: Finished Permit User Sessions.1928test # [ 8.618853] systemd-logind[629]: Watching system buttons on /dev/input/event2 (Power Button)1929test # [ 8.623108] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1930test # [ 8.625758] systemd[1]: Starting Permit User Sessions...1931second # [ 8.819295] ppdev: user-space parallel port driver1932second # [ 8.863415] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1933second # [ 8.687816] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1934second # [ 8.690176] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1935second # [ 8.698090] systemd-logind[596]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1936second # [ 8.709439] systemd[1]: Started Getty on tty1.1937second # [ 8.710373] systemd[1]: Reached target Login Prompts.1938second # [ 8.711299] systemd[1]: Reached target Multi-User System.1939second # [ 8.718341] systemd[1]: Starting Virtual Console Setup...1940second # [ 8.917117] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1941test # [ 8.899063] ppdev: user-space parallel port driver1942test # [ 8.906734] Console: switching to colour frame buffer device 160x501943second # [ 8.761220] systemd-resolved[345]: eth0: Bus client set default route setting: yes1944test # [ 8.942914] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1945test # [ 8.689536] systemd[1]: Finished Permit User Sessions.1946test # [ 8.773938] NetworkManager[574]: <warn> [1790780636.4078] keyfile: load: "/var/run/NetworkManager/system-connections/one.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1947second # [ 8.768203] systemd-resolved[345]: eth0: Bus client set DNS server list to: 10.0.2.31948test # [ 8.778523] NetworkManager[574]: <warn> [1790780636.4096] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1949test # [ 8.783835] systemd-logind[629]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1950test # [ 8.785530] systemd[1]: Started Getty on tty1.1951test # [ 8.788047] systemd[1]: Reached target Login Prompts.1952test # [ 8.788939] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1953test # [ 8.791474] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1954test # [ 8.966450] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1955test # [ 8.795399] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1956test # [ 8.798085] systemd[1]: Stopped Virtual Console Setup.1957test # [ 8.990110] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1958test # [ 8.848552] systemd-resolved[370]: eth0: Bus client set default route setting: yes1959test # [ 8.854153] systemd-resolved[370]: eth0: Bus client set DNS server list to: 10.0.2.31960second # [ 9.067299] kvm_amd: TSC scaling supported1961second # [ 9.067836] kvm_amd: Nested Virtualization enabled1962second # [ 9.068995] kvm_amd: Nested Paging enabled1963second # [ 9.069689] kvm_amd: LBR virtualization supported1964second # [ 9.070248] kvm_amd: Virtual GIF supported1965second # [ 9.071211] kvm_amd: Virtual NMI enabled1966second # [ 8.897846] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1967second # [ 8.901984] systemd[1]: Stopped Virtual Console Setup.1968test # [ 9.088935] kvm_amd: TSC scaling supported1969test # [ 9.089572] kvm_amd: Nested Virtualization enabled1970test # [ 9.090145] kvm_amd: Nested Paging enabled1971test # [ 9.091083] kvm_amd: LBR virtualization supported1972test # [ 9.091850] kvm_amd: Virtual GIF supported1973test # [ 9.092526] kvm_amd: Virtual NMI enabled1974second # [ 8.915872] systemd[1]: Starting Virtual Console Setup...1975second # [ 9.127875] EDAC MC: Ver: 3.0.01976test # [ 9.132652] EDAC MC: Ver: 3.0.01977test # [ 8.987363] systemd[1]: Reached target Multi-User System.1978test # [ 8.991105] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1979test # [ 8.995986] systemd[1]: Starting Virtual Console Setup...1980second # [ 9.016159] systemd-vconsole-setup[649]: Configuration of first virtual console was skipped, ignoring remaining ones.1981second # [ 9.020356] systemd[1]: Finished Virtual Console Setup.1982second # [ 9.023769] systemd[1]: Startup finished in 787ms (kernel) + 4.338s (initrd) + 3.897s (userspace) = 9.023s.1983test # [ 9.095980] systemd-vconsole-setup[687]: Configuration of first virtual console was skipped, ignoring remaining ones.1984test # [ 9.099874] systemd[1]: Finished Virtual Console Setup.1985test # [ 9.104273] systemd[1]: Startup finished in 785ms (kernel) + 4.348s (initrd) + 3.969s (userspace) = 9.103s.1986test: (finished: waiting for unit NetworkManager.service, in 10.03 seconds)1987test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1988test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.01 seconds)1989test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets1990test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets, in 0.01 seconds)1991test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection1992test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.01 seconds)1993(finished: run the VM test script, in 10.16 seconds)1994test script finished in 10.17s1995cleanup1996kill QemuMachine (pid 47)1997second # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/lb41b0anx1f98y9y5s9mdv97gjgsq740-python3-3.14.7/bin/python3.14)1998second # [2026-09-30T15:03:57Z INFO virtiofsd] Client disconnected, shutting down1999second # [2026-09-30T15:03:57Z INFO virtiofsd] Client disconnected, shutting down2000second # [2026-09-30T15:03:57Z INFO virtiofsd] Client disconnected, shutting down2001kill QemuMachine (pid 48)2002test # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/lb41b0anx1f98y9y5s9mdv97gjgsq740-python3-3.14.7/bin/python3.14)2003test # [2026-09-30T15:03:57Z INFO virtiofsd] Client disconnected, shutting down2004test # [2026-09-30T15:03:57Z INFO virtiofsd] Client disconnected, shutting down2005test # [2026-09-30T15:03:57Z INFO virtiofsd] Client disconnected, shutting down2006(finished: cleanup, in 0.19 seconds)