vm-test-run-wifi
checks.x86_64-linux.wifi
· build #287
· raw
1build-x86-01: building ms8nq9fs136kv5khxg57yc0a1qbf1271-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: QEMU running (pid 48)16test # Formatting '/build/vm-state-test/tmp.1UdGYa9POO', fmt=raw size=107374182417test # 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: 29b71b4d-e13e-4c52-8868-b72b2326d50a21test # 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-10-19T20:04:13Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)32test # [2026-10-19T20:04:13Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether33test # [2026-10-19T20:04:13Z INFO virtiofsd] Waiting for vhost-user socket connection...34test # [2026-10-19T20:04:13Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)35test # [2026-10-19T20:04:13Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)36test # [2026-10-19T20:04:13Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether37test # [2026-10-19T20:04:13Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether38test # [2026-10-19T20:04:13Z INFO virtiofsd] Waiting for vhost-user socket connection...39test # [2026-10-19T20:04:13Z INFO virtiofsd] Waiting for vhost-user socket connection...40test # [2026-10-19T20:04:13Z INFO virtiofsd] Client connected, servicing requests41test # [2026-10-19T20:04:13Z INFO virtiofsd] Client connected, servicing requests42test # [2026-10-19T20:04:13Z INFO virtiofsd] Client connected, servicing requests43second # Disk image does not exist, creating the virtualisation disk image...44second: QEMU running (pid 47)45second # Formatting '/build/vm-state-second/tmp.0lBlv0MHyh', fmt=raw size=107374182446second # mke2fs 1.47.4 (6-Mar-2025)47second # Discarding device blocks: 0/262144 done48second # Creating filesystem with 262144 4k blocks and 65536 inodes49(finished: start all VMs, in 0.09 seconds)50second # Filesystem UUID: 2191330f-035f-4333-9c84-a2182456a2d251test: waiting for unit NetworkManager.service52second # Superblock backups stored on blocks:53test: waiting for the VM to finish booting54second # 32768, 98304, 163840, 22937655second # 56second # Allocating group tables: 0/8 done57second # Writing inode tables: 0/8 done58second # Creating journal (8192 blocks): done59second # Writing superblocks and filesystem accounting information: 0/8 done60second # 61second # Virtualisation disk image created.62second # Starting virtiofs daemons...63second # [2026-10-19T20:04:13Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)64second # [2026-10-19T20:04:13Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether65second # [2026-10-19T20:04:13Z INFO virtiofsd] Waiting for vhost-user socket connection...66second # [2026-10-19T20:04:13Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)67second # [2026-10-19T20:04:13Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether68second # [2026-10-19T20:04:13Z INFO virtiofsd] Waiting for vhost-user socket connection...69second # [2026-10-19T20:04:13Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)70second # [2026-10-19T20:04:13Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether71second # [2026-10-19T20:04:13Z INFO virtiofsd] Waiting for vhost-user socket connection...72second # [2026-10-19T20:04:13Z INFO virtiofsd] Client connected, servicing requests73second # [2026-10-19T20:04:13Z INFO virtiofsd] Client connected, servicing requests74second # [2026-10-19T20:04:13Z 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 # 79second # 80second # 81test # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0082second # 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 # 88second # Press Ctrl-B to configure iPXE (PCI 00:02.0)...89second # 90second # 91second # 92second # 93test # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0094second # 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)... o[ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC @1791725172104test # [ 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/5bryzfaan22xzg608dhvwpbk9adxk9im-nixos-system-test-test/init regInfo=/nix/.ro-store/6v6kwmkacrj1c832crxvw7h7a2jpg58g-closure-info/registration console=ttyS0,115200n8 console=tty0105test # [ 0.000000] BIOS-provided physical RAM map:106test # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable107second # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC @1791725172108test # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved109test # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved110test # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable111test # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved112second # [ 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/367gaijfmb1k89m7r75wcjr725dfd2jk-nixos-system-second-test/init regInfo=/nix/.ro-store/sy5ffbhiy1ldkhap13h6cgygk7pm30vv-closure-info/registration console=ttyS0,115200n8 console=tty0113test # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved114second # [ 0.000000] BIOS-provided physical RAM map:115test # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved116second # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable117test # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved118second # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved119test # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved120second # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved121test # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved122second # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable123test # [ 0.000000] NX (Execute Disable) protection: active124second # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved125test # [ 0.000000] APIC: Static calls initialized126test # [ 0.000000] SMBIOS 2.8 present.127second # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved128test # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014129second # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved130test # [ 0.000000] DMI: Memory slots populated: 1/1131second # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved132test # [ 0.000000] Hypervisor detected: KVM133test # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000134second # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved135test # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00136second # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved137test # [ 0.000000] kvm-clock: using sched offset of 386080122 cycles138second # [ 0.000000] NX (Execute Disable) protection: active139second # [ 0.000000] APIC: Static calls initialized140second # [ 0.000000] SMBIOS 2.8 present.141test # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns142test # [ 0.000005] tsc: Detected 4192.124 MHz processor143second # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014144test # [ 0.000544] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000145second # [ 0.000000] DMI: Memory slots populated: 1/1146second # [ 0.000000] Hypervisor detected: KVM147test # [ 0.000571] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs148second # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000149test # [ 0.000573] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT150second # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00151test # [ 0.002151] found SMP MP-table at [mem 0x000f5450-0x000f545f]152second # [ 0.000000] kvm-clock: using sched offset of 390634391 cycles153test # [ 0.002167] Using GB pages for direct mapping154test # [ 0.002247] RAMDISK: [mem 0x3e2e3000-0x3ffcffff]155second # [ 0.000001] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns156test # [ 0.002252] ACPI: Early table checksum verification disabled157second # [ 0.000004] tsc: Detected 4192.124 MHz processor158test # [ 0.002255] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )159second # [ 0.000541] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000160test # [ 0.002258] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)161second # [ 0.000568] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs162test # [ 0.002261] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)163second # [ 0.000571] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT164second # [ 0.002136] found SMP MP-table at [mem 0x000f5450-0x000f545f]165test # [ 0.002266] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)166second # [ 0.002150] Using GB pages for direct mapping167test # [ 0.002268] ACPI: FACS 0x000000003FFE0000 000040168second # [ 0.002236] RAMDISK: [mem 0x3e2e4000-0x3ffcffff]169second # [ 0.002240] ACPI: Early table checksum verification disabled170test # [ 0.002269] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)171second # [ 0.002242] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )172test # [ 0.002271] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)173second # [ 0.002245] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)174test # [ 0.002273] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)175second # [ 0.002250] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)176test # [ 0.002274] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)177second # [ 0.002255] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)178test # [ 0.002275] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]179second # [ 0.002256] ACPI: FACS 0x000000003FFE0000 000040180test # [ 0.002276] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]181second # [ 0.002257] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)182test # [ 0.002276] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]183second # [ 0.002259] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)184test # [ 0.002277] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]185test # [ 0.002277] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]186second # [ 0.002260] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)187test # [ 0.002278] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]188second # [ 0.002261] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)189test # [ 0.002278] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]190second # [ 0.002262] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]191test # [ 0.002613] No NUMA configuration found192second # [ 0.002263] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]193test # [ 0.002614] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]194second # [ 0.002263] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]195test # [ 0.002617] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]196test # [ 0.002685] Zone ranges:197second # [ 0.002263] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]198test # [ 0.002686] DMA [mem 0x0000000000001000-0x0000000000ffffff]199second # [ 0.002264] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]200test # [ 0.002687] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]201test # [ 0.002688] Normal empty202second # [ 0.002264] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]203test # [ 0.002688] Device empty204test # [ 0.002689] Movable zone start for each node205second # [ 0.002264] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]206test # [ 0.002689] Early memory node ranges207second # [ 0.002600] No NUMA configuration found208test # [ 0.002689] node 0: [mem 0x0000000000001000-0x000000000009efff]209second # [ 0.002601] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]210test # [ 0.002690] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]211second # [ 0.002603] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]212second # [ 0.002671] Zone ranges:213test # [ 0.002691] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]214second # [ 0.002672] DMA [mem 0x0000000000001000-0x0000000000ffffff]215test # [ 0.002724] On node 0, zone DMA: 1 pages in unavailable ranges216second # [ 0.002673] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]217test # [ 0.002908] On node 0, zone DMA: 97 pages in unavailable ranges218second # [ 0.002674] Normal empty219second # [ 0.002674] Device empty220test # [ 0.015722] On node 0, zone DMA32: 40 pages in unavailable ranges221second # [ 0.002675] Movable zone start for each node222test # [ 0.016475] ACPI: PM-Timer IO Port: 0x608223second # [ 0.002675] Early memory node ranges224test # [ 0.016485] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])225second # [ 0.002676] node 0: [mem 0x0000000000001000-0x000000000009efff]226test # [ 0.016503] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23227second # [ 0.002676] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]228test # [ 0.016505] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)229second # [ 0.002677] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]230test # [ 0.016506] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)231second # [ 0.002693] On node 0, zone DMA: 1 pages in unavailable ranges232test # [ 0.016507] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)233second # [ 0.002876] On node 0, zone DMA: 97 pages in unavailable ranges234test # [ 0.016508] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)235second # [ 0.015626] On node 0, zone DMA32: 40 pages in unavailable ranges236second # [ 0.016375] ACPI: PM-Timer IO Port: 0x608237test # [ 0.016509] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)238second # [ 0.016384] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])239test # [ 0.016510] ACPI: Using ACPI (MADT) for SMP configuration information240second # [ 0.016402] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23241test # [ 0.016511] ACPI: HPET id: 0x8086a201 base: 0xfed00000242test # [ 0.016513] TSC deadline timer available243second # [ 0.016404] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)244test # [ 0.016517] CPU topo: Max. logical packages: 1245second # [ 0.016405] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)246test # [ 0.016517] CPU topo: Max. logical dies: 1247test # [ 0.016518] CPU topo: Max. dies per package: 1248second # [ 0.016406] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)249test # [ 0.016521] CPU topo: Max. threads per core: 1250second # [ 0.016407] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)251test # [ 0.016521] CPU topo: Num. cores per package: 1252second # [ 0.016407] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)253test # [ 0.016521] CPU topo: Num. threads per package: 1254test # [ 0.016521] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs255second # [ 0.016409] ACPI: Using ACPI (MADT) for SMP configuration information256second # [ 0.016409] ACPI: HPET id: 0x8086a201 base: 0xfed00000257test # [ 0.016533] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()258second # [ 0.016411] TSC deadline timer available259second # [ 0.016415] CPU topo: Max. logical packages: 1260test # [ 0.016557] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]261second # [ 0.016416] CPU topo: Max. logical dies: 1262test # [ 0.016558] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]263second # [ 0.016417] CPU topo: Max. dies per package: 1264second # [ 0.016420] CPU topo: Max. threads per core: 1265test # [ 0.016559] [mem 0x40000000-0xafffffff] available for PCI devices266second # [ 0.016420] CPU topo: Num. cores per package: 1267test # [ 0.016560] Booting paravirtualized kernel on KVM268second # [ 0.016420] CPU topo: Num. threads per package: 1269test # [ 0.016562] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns270second # [ 0.016421] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs271second # [ 0.016432] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()272test # [ 0.019831] setup_percpu: NR_CPUS:512 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1273test # [ 0.021404] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152274second # [ 0.016456] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]275test # [ 0.021435] kvm-guest: PV spinlocks disabled, single CPU276second # [ 0.016457] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]277second # [ 0.016458] [mem 0x40000000-0xafffffff] available for PCI devices278second # [ 0.016459] Booting paravirtualized kernel on KVM279second # [ 0.016461] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns280test # [ 0.021436] 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/5bryzfaan22xzg608dhvwpbk9adxk9im-nixos-system-test-test/init regInfo=/nix/.ro-store/6v6kwmkacrj1c832crxvw7h7a2jpg58g-closure-info/registration console=ttyS0,115200n8 console=tty0281second # [ 0.019758] setup_percpu: NR_CPUS:512 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1282second # [ 0.021617] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152283second # [ 0.021649] kvm-guest: PV spinlocks disabled, single CPU284test # [ 0.021501] Unknown kernel command line parameters "regInfo=/nix/.ro-store/6v6kwmkacrj1c832crxvw7h7a2jpg58g-closure-info/registration", will be passed to user space.285test # [ 0.021690] random: crng init done286test # [ 0.021691] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes287test # [ 0.021706] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)288test # [ 0.022093] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)289second # [ 0.021650] 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/367gaijfmb1k89m7r75wcjr725dfd2jk-nixos-system-second-test/init regInfo=/nix/.ro-store/sy5ffbhiy1ldkhap13h6cgygk7pm30vv-closure-info/registration console=ttyS0,115200n8 console=tty0290test # [ 0.022117] Fallback order for Node 0: 0291test # [ 0.022119] Built 1 zonelists, mobility grouping on. Total pages: 262006292test # [ 0.022120] Policy zone: DMA32293second # [ 0.021719] Unknown kernel command line parameters "regInfo=/nix/.ro-store/sy5ffbhiy1ldkhap13h6cgygk7pm30vv-closure-info/registration", will be passed to user space.294test # [ 0.024129] mem auto-init: stack:all(zero), heap alloc:on, heap free:off295second # [ 0.021909] random: crng init done296test # [ 0.026339] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1297second # [ 0.021910] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes298test # [ 0.027967] allocated 2097152 bytes of page_ext299second # [ 0.021925] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)300test # [ 0.037249] ftrace: allocating 48842 entries in 192 pages301test # [ 0.037250] ftrace: allocated 192 pages with 2 groups302second # [ 0.022313] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)303test # [ 0.037926] Dynamic Preempt: lazy304second # [ 0.022342] Fallback order for Node 0: 0305test # [ 0.038047] rcu: Preemptible hierarchical RCU implementation.306second # [ 0.022344] Built 1 zonelists, mobility grouping on. Total pages: 262006307test # [ 0.038048] rcu: RCU event tracing is enabled.308second # [ 0.022345] Policy zone: DMA32309test # [ 0.038048] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.310second # [ 0.024115] mem auto-init: stack:all(zero), heap alloc:on, heap free:off311test # [ 0.038050] Trampoline variant of Tasks RCU enabled.312second # [ 0.026467] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1313test # [ 0.038050] Rude variant of Tasks RCU enabled.314second # [ 0.028078] allocated 2097152 bytes of page_ext315test # [ 0.038050] Tracing variant of Tasks RCU enabled.316second # [ 0.036871] ftrace: allocating 48842 entries in 192 pages317test # [ 0.038051] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.318second # [ 0.036872] ftrace: allocated 192 pages with 2 groups319second # [ 0.037541] Dynamic Preempt: lazy320test # [ 0.038052] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1321second # [ 0.037673] rcu: Preemptible hierarchical RCU implementation.322second # [ 0.037674] rcu: RCU event tracing is enabled.323test # [ 0.038069] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.324second # [ 0.037675] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.325test # [ 0.038070] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.326second # [ 0.037676] Trampoline variant of Tasks RCU enabled.327second # [ 0.037676] Rude variant of Tasks RCU enabled.328test # [ 0.038070] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.329second # [ 0.037676] Tracing variant of Tasks RCU enabled.330test # [ 0.041196] NR_IRQS: 33024, nr_irqs: 256, preallocated irqs: 16331second # [ 0.037677] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.332test # [ 0.041429] rcu: srcu_init: Setting srcu_struct sizes based on contention.333second # [ 0.037678] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1334test # [ 0.041435] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns335second # [ 0.037689] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.336test # [ 0.041590] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)337second # [ 0.037691] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.338test # [ 0.046548] Console: colour VGA+ 80x25339test # [ 0.046551] printk: legacy console [tty0] enabled340second # [ 0.037691] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.341test # [ 0.075839] printk: legacy console [ttyS0] enabled342second # [ 0.041062] NR_IRQS: 33024, nr_irqs: 256, preallocated irqs: 16343test # [ 0.204785] ACPI: Core revision 20250807344second # [ 0.041295] rcu: srcu_init: Setting srcu_struct sizes based on contention.345test # [ 0.205829] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns346second # [ 0.041299] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns347test # [ 0.207697] APIC: Switch to symmetric I/O mode setup348test # [ 0.208829] x2apic enabled349second # [ 0.041459] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)350second # [ 0.046391] Console: colour VGA+ 80x25351test # [ 0.209611] APIC: Switched APIC routing to: physical x2apic352second # [ 0.046394] printk: legacy console [tty0] enabled353second # [ 0.075701] printk: legacy console [ttyS0] enabled354second # [ 0.204794] ACPI: Core revision 20250807355test # [ 0.211534] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1356second # [ 0.205815] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns357test # [ 0.212731] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3c6d51807ee, max_idle_ns: 440795380511 ns358second # [ 0.207681] APIC: Switch to symmetric I/O mode setup359second # [ 0.208811] x2apic enabled360test # [ 0.214748] Calibrating delay loop (skipped) preset value.. 8384.24 BogoMIPS (lpj=4192124)361second # [ 0.209593] APIC: Switched APIC routing to: physical x2apic362test # [ 0.215831] x86/cpu: User Mode Instruction Prevention (UMIP) activated363test # [ 0.217850] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127364second # [ 0.211519] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1365test # [ 0.218747] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0366second # [ 0.212715] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3c6d51807ee, max_idle_ns: 440795380511 ns367test # [ 0.219750] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto368second # [ 0.214743] Calibrating delay loop (skipped) preset value.. 8384.24 BogoMIPS (lpj=4192124)369test # [ 0.220747] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl370second # [ 0.215826] x86/cpu: User Mode Instruction Prevention (UMIP) activated371test # [ 0.221748] Spectre V2 : Mitigation: Enhanced / Automatic IBRS372second # [ 0.217835] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127373test # [ 0.222747] Speculative Return Stack Overflow: Mitigation: Safe RET374second # [ 0.218743] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0375test # [ 0.223747] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization376second # [ 0.219747] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto377second # [ 0.220743] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl378second # [ 0.221743] Spectre V2 : Mitigation: Enhanced / Automatic IBRS379test # [ 0.225753] Spectre V2 : Enabling IBPB for BPF380second # [ 0.222743] Speculative Return Stack Overflow: Mitigation: Safe RET381test # [ 0.227455] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier382second # [ 0.223743] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization383test # [ 0.228747] active return thunk: srso_alias_return_thunk384test # [ 0.229768] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'385test # [ 0.230747] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'386test # [ 0.231747] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'387second # [ 0.224748] Spectre V2 : Enabling IBPB for BPF388test # [ 0.232747] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'389second # [ 0.226448] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier390test # [ 0.233747] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'391second # [ 0.227743] active return thunk: srso_alias_return_thunk392test # [ 0.234747] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'393second # [ 0.228762] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'394test # [ 0.236747] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'395second # [ 0.229743] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'396test # [ 0.237747] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'397second # [ 0.230743] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'398second # [ 0.231743] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'399test # [ 0.239747] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'400second # [ 0.232743] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'401test # [ 0.240747] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256402second # [ 0.233743] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'403test # [ 0.242747] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64404test # [ 0.243747] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512405second # [ 0.235743] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'406test # [ 0.244747] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024407second # [ 0.236743] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'408test # [ 0.245747] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8409second # [ 0.237743] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'410test # [ 0.246747] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16411second # [ 0.238743] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256412test # [ 0.248747] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24413second # [ 0.240743] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64414test # [ 0.249747] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format.415second # [ 0.241743] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512416second # [ 0.242743] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024417second # [ 0.243743] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8418second # [ 0.244743] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16419second # [ 0.245743] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24420second # [ 0.247743] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format.421test # [ 0.274836] Freeing SMP alternatives memory: 48K422test # [ 0.275748] pid_max: default: 32768 minimum: 301423test # [ 0.276701] LSM: initializing lsm=capability,landlock,yama,bpf,ima424test # [ 0.277842] landlock: Up and running.425test # [ 0.278747] Yama: becoming mindful.426test # [ 0.279650] LSM support for eBPF active427test # [ 0.280428] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)428second # [ 0.272490] Freeing SMP alternatives memory: 48K429second # [ 0.272744] pid_max: default: 32768 minimum: 301430test # [ 0.281764] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)431second # [ 0.273782] LSM: initializing lsm=capability,landlock,yama,bpf,ima432second # [ 0.274826] landlock: Up and running.433test # [ 0.284241] smpboot: CPU0: AMD Ryzen 9 7950X3D 16-Core Processor (family: 0x19, model: 0x61, stepping: 0x2)434second # [ 0.276315] Yama: becoming mindful.435second # [ 0.276918] LSM support for eBPF active436test # [ 0.285171] Performance Events: Fam17h+ core perfctr, AMD PMU driver.437second # [ 0.277816] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)438test # [ 0.285750] ... version: 2439test # [ 0.286605] ... bit width: 48440second # [ 0.278760] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)441test # [ 0.287425] ... generic counters: 6442test # [ 0.287756] ... generic bitmap: 000000000000003f443test # [ 0.288748] ... fixed-purpose counters: 0444second # [ 0.281861] smpboot: CPU0: AMD Ryzen 9 7950X3D 16-Core Processor (family: 0x19, model: 0x61, stepping: 0x2)445test # [ 0.289605] ... fixed-purpose bitmap: 0000000000000000446second # [ 0.283131] Performance Events: Fam17h+ core perfctr, AMD PMU driver.447test # [ 0.290621] ... value mask: 0000ffffffffffff448second # [ 0.283750] ... version: 2449test # [ 0.291613] ... max period: 00007fffffffffff450second # [ 0.284606] ... bit width: 48451test # [ 0.292620] ... global_ctrl mask: 000000000000003f452second # [ 0.285430] ... generic counters: 6453test # [ 0.293691] signal: max sigframe size: 3376454second # [ 0.285744] ... generic bitmap: 000000000000003f455test # [ 0.294464] rcu: Hierarchical SRCU implementation.456second # [ 0.286744] ... fixed-purpose counters: 0457test # [ 0.294751] rcu: Max phase no-delay instances is 400.458second # [ 0.287607] ... fixed-purpose bitmap: 0000000000000000459second # [ 0.288617] ... value mask: 0000ffffffffffff460second # [ 0.289615] ... max period: 00007fffffffffff461second # [ 0.290616] ... global_ctrl mask: 000000000000003f462test # [ 0.298674] smp: Bringing up secondary CPUs ...463second # [ 0.291673] signal: max sigframe size: 3376464test # [ 0.299475] smp: Brought up 1 node, 1 CPU465second # [ 0.292460] rcu: Hierarchical SRCU implementation.466test # [ 0.299749] smpboot: Total of 1 processors activated (8384.24 BogoMIPS)467second # [ 0.292747] rcu: Max phase no-delay instances is 400.468test # [ 0.300870] Memory: 942484K/1048024K available (18132K kernel code, 2734K rwdata, 13652K rodata, 3660K init, 3620K bss, 98120K reserved, 0K cma-reserved)469test # [ 0.301893] devtmpfs: initialized470second # [ 0.296649] smp: Bringing up secondary CPUs ...471test # [ 0.302743] x86/mm: Memory block size: 128MB472second # [ 0.297469] smp: Brought up 1 node, 1 CPU473second # [ 0.297745] smpboot: Total of 1 processors activated (8384.24 BogoMIPS)474test # [ 0.304300] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)475test # [ 0.304771] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).476second # [ 0.298868] Memory: 942452K/1048024K available (18132K kernel code, 2734K rwdata, 13652K rodata, 3660K init, 3620K bss, 98116K reserved, 0K cma-reserved)477test # [ 0.305811] pinctrl core: initialized pinctrl subsystem478second # [ 0.299895] devtmpfs: initialized479test # [ 0.307013] PM: RTC time: 20:04:13, date: 2026-10-19480second # [ 0.300810] x86/mm: Memory block size: 128MB481second # [ 0.302358] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)482test # [ 0.309754] NET: Registered PF_NETLINK/PF_ROUTE protocol family483second # [ 0.302767] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).484test # [ 0.311001] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations485second # [ 0.303812] pinctrl core: initialized pinctrl subsystem486test # [ 0.311764] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations487second # [ 0.304963] PM: RTC time: 20:04:13, date: 2026-10-19488test # [ 0.312854] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations489test # [ 0.313755] audit: initializing netlink subsys (disabled)490second # [ 0.307655] NET: Registered PF_NETLINK/PF_ROUTE protocol family491test # [ 0.314922] thermal_sys: Registered thermal governor 'fair_share'492second # [ 0.308933] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations493test # [ 0.314924] thermal_sys: Registered thermal governor 'bang_bang'494second # [ 0.309765] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations495test # [ 0.315749] thermal_sys: Registered thermal governor 'step_wise'496second # [ 0.310850] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations497test # [ 0.316751] audit: type=2000 audit(1792440254.230:1): state=initialized audit_enabled=0 res=1498second # [ 0.311751] audit: initializing netlink subsys (disabled)499test # [ 0.318750] thermal_sys: Registered thermal governor 'user_space'500second # [ 0.312936] thermal_sys: Registered thermal governor 'fair_share'501test # [ 0.318751] thermal_sys: Registered thermal governor 'power_allocator'502test # [ 0.319768] cpuidle: using governor menu503second # [ 0.312938] thermal_sys: Registered thermal governor 'bang_bang'504second # [ 0.313745] thermal_sys: Registered thermal governor 'step_wise'505test # [ 0.322345] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5506second # [ 0.314747] audit: type=2000 audit(1792440254.234:1): state=initialized audit_enabled=0 res=1507test # [ 0.322942] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]508second # [ 0.316746] thermal_sys: Registered thermal governor 'user_space'509test # [ 0.323751] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry510second # [ 0.316747] thermal_sys: Registered thermal governor 'power_allocator'511test # [ 0.324756] PCI: Using configuration type 1 for base access512second # [ 0.317755] cpuidle: using governor menu513test # [ 0.325901] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.514second # [ 0.320344] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5515second # [ 0.320951] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]516second # [ 0.321747] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry517second # [ 0.322751] PCI: Using configuration type 1 for base access518second # [ 0.323897] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.519test # [ 0.332911] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages520test # [ 0.333749] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page521second # [ 0.328968] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages522second # [ 0.329744] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page523test # [ 0.338748] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages524test # [ 0.339749] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page525second # [ 0.334744] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages526second # [ 0.335744] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page527test # [ 0.345999] ACPI: Added _OSI(Module Device)528test # [ 0.346749] ACPI: Added _OSI(Processor Device)529test # [ 0.347643] ACPI: Added _OSI(Processor Aggregator Device)530second # [ 0.343941] ACPI: Added _OSI(Module Device)531test # [ 0.352527] ACPI: 1 ACPI AML tables successfully acquired and loaded532second # [ 0.344745] ACPI: Added _OSI(Processor Device)533second # [ 0.345637] ACPI: Added _OSI(Processor Aggregator Device)534test # [ 0.358502] ACPI: Interpreter enabled535test # [ 0.358760] ACPI: PM: (supports S0 S3 S4 S5)536test # [ 0.359627] ACPI: Using IOAPIC for interrupt routing537second # [ 0.352536] ACPI: 1 ACPI AML tables successfully acquired and loaded538test # [ 0.360566] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug539test # [ 0.362141] PCI: Using E820 reservations for host bridge windows540test # [ 0.362855] ACPI: Enabled 2 GPEs in block 00 to 3F541second # [ 0.356513] ACPI: Interpreter enabled542second # [ 0.356756] ACPI: PM: (supports S0 S3 S4 S5)543second # [ 0.357621] ACPI: Using IOAPIC for interrupt routing544second # [ 0.358581] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug545test # [ 0.366822] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])546second # [ 0.360238] PCI: Using E820 reservations for host bridge windows547test # [ 0.367753] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]548second # [ 0.360851] ACPI: Enabled 2 GPEs in block 00 to 3F549test # [ 0.368805] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]550test # [ 0.369840] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]551test # [ 0.371124] PCI host bridge to bus 0000:00552second # [ 0.364824] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])553test # [ 0.371753] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]554second # [ 0.365748] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]555test # [ 0.372749] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]556second # [ 0.366801] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]557test # [ 0.373749] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]558second # [ 0.367835] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]559test # [ 0.374749] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]560second # [ 0.369119] PCI host bridge to bus 0000:00561test # [ 0.375749] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]562second # [ 0.369749] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]563test # [ 0.376749] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]564second # [ 0.370745] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]565test # [ 0.377749] pci_bus 0000:00: root bus resource [bus 00-ff]566second # [ 0.371745] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]567test # [ 0.378853] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint568second # [ 0.372745] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]569second # [ 0.373745] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]570test # [ 0.380364] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint571second # [ 0.374745] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]572second # [ 0.375745] pci_bus 0000:00: root bus resource [bus 00-ff]573second # [ 0.376830] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint574test # [ 0.382710] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]575test # [ 0.383768] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]576second # [ 0.378357] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint577test # [ 0.384785] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]578test # [ 0.385910] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]579second # [ 0.380741] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]580test # [ 0.387442] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint581second # [ 0.381763] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]582second # [ 0.382781] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]583test # [ 0.388758] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]584test # [ 0.389759] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]585second # [ 0.383904] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]586test # [ 0.390785] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]587second # [ 0.385450] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint588test # [ 0.391759] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]589test # [ 0.393565] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint590second # [ 0.387754] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]591second # [ 0.388754] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]592second # [ 0.389781] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]593test # [ 0.396758] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]594second # [ 0.390754] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]595test # [ 0.397758] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]596test # [ 0.398785] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]597second # [ 0.392687] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint598test # [ 0.400702] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint599second # [ 0.395356] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]600second # [ 0.395754] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]601test # [ 0.402759] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]602second # [ 0.396781] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]603test # [ 0.403759] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]604test # [ 0.404786] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]605second # [ 0.398591] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint606test # [ 0.406603] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint607second # [ 0.400754] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]608second # [ 0.401754] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]609test # [ 0.408758] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]610second # [ 0.402781] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]611test # [ 0.409759] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]612test # [ 0.410785] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]613second # [ 0.404567] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint614test # [ 0.411758] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]615second # [ 0.406754] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]616test # [ 0.413566] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint617second # [ 0.407760] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]618second # [ 0.408781] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]619test # [ 0.415707] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]620second # [ 0.409754] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]621test # [ 0.416709] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]622second # [ 0.411672] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint623test # [ 0.418568] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint624second # [ 0.413513] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]625second # [ 0.413781] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]626test # [ 0.420748] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]627test # [ 0.421785] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]628second # [ 0.415560] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint629test # [ 0.423618] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint630second # [ 0.417706] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]631test # [ 0.425685] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]632second # [ 0.418701] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]633test # [ 0.426696] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]634second # [ 0.420604] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint635test # [ 0.428633] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint636second # [ 0.422494] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]637second # [ 0.422781] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]638test # [ 0.430653] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]639test # [ 0.431712] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]640second # [ 0.424562] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint641test # [ 0.433575] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint642second # [ 0.426744] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]643second # [ 0.427781] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]644test # [ 0.435768] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]645test # [ 0.436759] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]646second # [ 0.429557] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint647test # [ 0.437785] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]648second # [ 0.431731] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]649second # [ 0.432573] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]650test # [ 0.439566] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint651second # [ 0.433697] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]652test # [ 0.441758] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]653test # [ 0.442759] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]654second # [ 0.435673] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint655test # [ 0.443785] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]656second # [ 0.437650] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]657second # [ 0.438575] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]658test # [ 0.445605] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint659second # [ 0.439707] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]660test # [ 0.447439] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]661second # [ 0.441597] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint662second # [ 0.443408] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]663test # [ 0.447996] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint664test # [ 0.450428] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]665second # [ 0.443985] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint666test # [ 0.450995] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint667second # [ 0.446307] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]668test # [ 0.452420] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]669second # [ 0.446977] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint670test # [ 0.453026] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint671second # [ 0.448322] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]672test # [ 0.454405] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]673second # [ 0.449025] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint674test # [ 0.455078] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint675second # [ 0.450283] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]676test # [ 0.456201] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO677second # [ 0.451059] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint678test # [ 0.457066] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint679second # [ 0.452319] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO680test # [ 0.458690] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]681second # [ 0.453065] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint682test # [ 0.459578] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]683second # [ 0.454481] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]684second # [ 0.454754] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]685test # [ 0.461098] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint686test # [ 0.462455] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]687second # [ 0.456187] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint688second # [ 0.457336] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]689test # [ 0.467098] ACPI: PCI: Interrupt link LNKA configured for IRQ 10690test # [ 0.467863] ACPI: PCI: Interrupt link LNKB configured for IRQ 10691second # [ 0.462256] ACPI: PCI: Interrupt link LNKA configured for IRQ 10692test # [ 0.468860] ACPI: PCI: Interrupt link LNKC configured for IRQ 11693second # [ 0.462856] ACPI: PCI: Interrupt link LNKB configured for IRQ 10694test # [ 0.469859] ACPI: PCI: Interrupt link LNKD configured for IRQ 11695second # [ 0.463854] ACPI: PCI: Interrupt link LNKC configured for IRQ 11696test # [ 0.470856] ACPI: PCI: Interrupt link LNKE configured for IRQ 10697second # [ 0.464851] ACPI: PCI: Interrupt link LNKD configured for IRQ 11698test # [ 0.471856] ACPI: PCI: Interrupt link LNKF configured for IRQ 10699second # [ 0.465851] ACPI: PCI: Interrupt link LNKE configured for IRQ 10700test # [ 0.472859] ACPI: PCI: Interrupt link LNKG configured for IRQ 11701second # [ 0.466864] ACPI: PCI: Interrupt link LNKF configured for IRQ 10702test # [ 0.473859] ACPI: PCI: Interrupt link LNKH configured for IRQ 11703second # [ 0.467856] ACPI: PCI: Interrupt link LNKG configured for IRQ 11704test # [ 0.474794] ACPI: PCI: Interrupt link GSIA configured for IRQ 16705second # [ 0.468854] ACPI: PCI: Interrupt link LNKH configured for IRQ 11706test # [ 0.475761] ACPI: PCI: Interrupt link GSIB configured for IRQ 17707second # [ 0.469789] ACPI: PCI: Interrupt link GSIA configured for IRQ 16708test # [ 0.476763] ACPI: PCI: Interrupt link GSIC configured for IRQ 18709second # [ 0.470756] ACPI: PCI: Interrupt link GSIB configured for IRQ 17710test # [ 0.477761] ACPI: PCI: Interrupt link GSID configured for IRQ 19711second # [ 0.471759] ACPI: PCI: Interrupt link GSIC configured for IRQ 18712test # [ 0.478760] ACPI: PCI: Interrupt link GSIE configured for IRQ 20713second # [ 0.472756] ACPI: PCI: Interrupt link GSID configured for IRQ 19714test # [ 0.479764] ACPI: PCI: Interrupt link GSIF configured for IRQ 21715second # [ 0.473756] ACPI: PCI: Interrupt link GSIE configured for IRQ 20716test # [ 0.480760] ACPI: PCI: Interrupt link GSIG configured for IRQ 22717second # [ 0.474756] ACPI: PCI: Interrupt link GSIF configured for IRQ 21718test # [ 0.481763] ACPI: PCI: Interrupt link GSIH configured for IRQ 23719second # [ 0.475759] ACPI: PCI: Interrupt link GSIG configured for IRQ 22720test # [ 0.483541] iommu: Default domain type: Translated721second # [ 0.476759] ACPI: PCI: Interrupt link GSIH configured for IRQ 23722test # [ 0.484513] iommu: DMA domain TLB invalidation policy: lazy mode723second # [ 0.478525] iommu: Default domain type: Translated724test # [ 0.484961] ACPI: bus type USB registered725second # [ 0.478752] iommu: DMA domain TLB invalidation policy: lazy mode726test # [ 0.485802] usbcore: registered new interface driver usbfs727second # [ 0.479958] ACPI: bus type USB registered728test # [ 0.486767] usbcore: registered new interface driver hub729second # [ 0.480792] usbcore: registered new interface driver usbfs730test # [ 0.487756] usbcore: registered new device driver usb731second # [ 0.481761] usbcore: registered new interface driver hub732test # [ 0.489401] NetLabel: Initializing733second # [ 0.482751] usbcore: registered new device driver usb734test # [ 0.489753] NetLabel: domain hash size = 128735second # [ 0.484358] NetLabel: Initializing736test # [ 0.490635] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO737second # [ 0.484748] NetLabel: domain hash size = 128738test # [ 0.491665] NetLabel: unlabeled traffic allowed by default739second # [ 0.485629] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO740test # [ 0.492638] PCI: Using ACPI for IRQ routing741second # [ 0.486656] NetLabel: unlabeled traffic allowed by default742second # [ 0.487632] PCI: Using ACPI for IRQ routing743test # [ 0.566017] pci 0000:00:01.0: vgaarb: setting as boot VGA device744test # [ 0.566745] pci 0000:00:01.0: vgaarb: bridge control possible745second # [ 0.560792] pci 0000:00:01.0: vgaarb: setting as boot VGA device746second # [ 0.561741] pci 0000:00:01.0: vgaarb: bridge control possible747test # [ 0.566745] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none748test # [ 0.566750] vgaarb: loaded749second # [ 0.561741] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none750test # [ 0.567518] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0751second # [ 0.561746] vgaarb: loaded752test # [ 0.567749] hpet0: 3 comparators, 64-bit 100.000000 MHz counter753second # [ 0.562511] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0754second # [ 0.562745] hpet0: 3 comparators, 64-bit 100.000000 MHz counter755test # [ 0.572805] clocksource: Switched to clocksource kvm-clock756second # [ 0.566807] clocksource: Switched to clocksource kvm-clock757test # [ 0.575902] VFS: Disk quotas dquot_6.6.0758second # [ 0.569806] VFS: Disk quotas dquot_6.6.0759test # [ 0.576734] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)760second # [ 0.570643] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)761test # [ 0.578251] pnp: PnP ACPI init762second # [ 0.572129] pnp: PnP ACPI init763test # [ 0.579082] ACPI: IRQ 4 override to edge(!), high(!)764second # [ 0.572947] ACPI: IRQ 4 override to edge(!), high(!)765test # [ 0.580166] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved766second # [ 0.574028] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved767test # [ 0.581890] pnp: PnP ACPI: found 5 devices768second # [ 0.575748] pnp: PnP ACPI: found 5 devices769test # [ 0.588919] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns770second # [ 0.582765] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns771test # [ 0.590608] clocksource: Switched to clocksource acpi_pm772second # [ 0.584458] clocksource: Switched to clocksource acpi_pm773test # [ 0.591729] NET: Registered PF_INET protocol family774second # [ 0.585590] NET: Registered PF_INET protocol family775test # [ 0.592862] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)776second # [ 0.586720] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)777second # [ 0.599754] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)778test # [ 0.606071] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)779test # [ 0.607840] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)780second # [ 0.601505] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)781test # [ 0.609351] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)782second # [ 0.603019] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)783second # [ 0.604563] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)784test # [ 0.610889] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)785second # [ 0.605983] TCP: Hash tables configured (established 8192 bind 8192)786test # [ 0.612347] TCP: Hash tables configured (established 8192 bind 8192)787test # [ 0.613637] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)788second # [ 0.607298] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)789test # [ 0.615107] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)790second # [ 0.608824] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)791test # [ 0.616455] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)792second # [ 0.610142] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)793test # [ 0.617864] NET: Registered PF_UNIX/PF_LOCAL protocol family794second # [ 0.611553] NET: Registered PF_UNIX/PF_LOCAL protocol family795second # [ 0.612689] NET: Registered PF_XDP protocol family796test # [ 0.619038] NET: Registered PF_XDP protocol family797second # [ 0.613691] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]798test # [ 0.620035] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]799second # [ 0.614898] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]800test # [ 0.621244] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]801second # [ 0.616101] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]802test # [ 0.622486] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]803second # [ 0.617431] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]804test # [ 0.623821] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]805second # [ 0.618776] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]806test # [ 0.625144] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]807second # [ 0.620102] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]808test # [ 0.626498] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]809second # [ 0.621939] ACPI: \_SB_.GSIA: Enabled at IRQ 16810test # [ 0.628375] ACPI: \_SB_.GSIA: Enabled at IRQ 16811second # [ 0.623815] ACPI: \_SB_.GSIB: Enabled at IRQ 17812test # [ 0.630267] ACPI: \_SB_.GSIB: Enabled at IRQ 17813second # [ 0.625629] ACPI: \_SB_.GSIC: Enabled at IRQ 18814test # [ 0.632145] ACPI: \_SB_.GSIC: Enabled at IRQ 18815second # [ 0.627689] ACPI: \_SB_.GSID: Enabled at IRQ 19816test # [ 0.634025] ACPI: \_SB_.GSID: Enabled at IRQ 19817second # [ 0.629285] PCI: CLS 0 bytes, default 64818test # [ 0.635711] PCI: CLS 0 bytes, default 64819second # [ 0.630277] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3c6d51807ee, max_idle_ns: 440795380511 ns820test # [ 0.636716] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3c6d51807ee, max_idle_ns: 440795380511 ns821second # [ 0.632294] Trying to unpack rootfs image as initramfs...822test # [ 0.638693] Trying to unpack rootfs image as initramfs...823second # [ 0.667814] Initialise system trusted keyrings824test # [ 0.674641] Initialise system trusted keyrings825second # [ 0.668898] workingset: timestamp_bits=40 max_order=18 bucket_order=0826test # [ 0.676386] workingset: timestamp_bits=40 max_order=18 bucket_order=0827second # [ 0.685587] Key type asymmetric registered828test # [ 0.692553] Key type asymmetric registered829second # [ 0.686432] Asymmetric key parser 'x509' registered830test # [ 0.695350] Asymmetric key parser 'x509' registered831second # [ 0.689517] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)832test # [ 0.696386] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)833second # [ 0.692507] io scheduler mq-deadline registered834second # [ 0.693414] io scheduler kyber registered835test # [ 0.702463] io scheduler mq-deadline registered836test # [ 0.703417] io scheduler kyber registered837second # [ 0.696906] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled838second # [ 0.698406] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A839test # [ 0.706787] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled840test # [ 0.708277] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A841second # [ 0.703865] Linux agpgart interface v0.103842second # [ 0.704768] ACPI: bus type drm_connector registered843test # [ 0.714282] Linux agpgart interface v0.103844second # [ 0.707806] usbcore: registered new interface driver usbserial_generic845test # [ 0.715202] ACPI: bus type drm_connector registered846second # [ 0.709092] usbserial: USB Serial support registered for generic847test # [ 0.717709] usbcore: registered new interface driver usbserial_generic848test # [ 0.718992] usbserial: USB Serial support registered for generic849second # [ 0.713483] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.850second # [ 0.713483] Please enable it if your BIOS has the CPPC option.851second # [ 0.716081] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled852test # [ 0.723347] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.853test # [ 0.723347] Please enable it if your BIOS has the CPPC option.854second # [ 0.719657] drop_monitor: Initializing network drop monitor service855test # [ 0.725954] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled856second # [ 0.720992] NET: Registered PF_INET6 protocol family857test # [ 0.730436] drop_monitor: Initializing network drop monitor service858second # [ 0.724834] Segment Routing with IPv6859test # [ 0.731755] NET: Registered PF_INET6 protocol family860second # [ 0.728494] In-situ OAM (IOAM) with IPv6861test # [ 0.735709] Segment Routing with IPv6862second # [ 0.729577] IPI shorthand broadcast: enabled863test # [ 0.739355] In-situ OAM (IOAM) with IPv6864test # [ 0.740475] IPI shorthand broadcast: enabled865second # [ 0.734853] sched_clock: Marking stable (566020209, 168442106)->(811759954, -77297639)866test # [ 0.745734] sched_clock: Marking stable (577020153, 168311240)->(823231996, -77900603)867second # [ 0.741601] registered taskstats version 1868second # [ 0.742640] Loading compiled-in X.509 certificates869test # [ 0.752508] registered taskstats version 1870test # [ 0.753531] Loading compiled-in X.509 certificates871second # [ 0.757476] Demotion targets for Node 0: null872second # [ 0.758475] Key type .fscrypt registered873second # [ 0.759273] Key type fscrypt-provisioning registered874test # [ 0.768340] Demotion targets for Node 0: null875second # [ 0.762569] ima: No TPM chip found, activating TPM-bypass!876test # [ 0.769352] Key type .fscrypt registered877second # [ 0.763666] ima: Allocated hash algorithm: sha1878test # [ 0.770162] Key type fscrypt-provisioning registered879test # [ 0.771256] ima: No TPM chip found, activating TPM-bypass!880second # [ 0.766499] ima: No architecture policies found881second # [ 0.769485] PM: Magic number: 14:89:94882test # [ 0.776347] ima: Allocated hash algorithm: sha1883test # [ 0.777303] ima: No architecture policies found884second # [ 0.770995] RAS: Correctable Errors collector initialized.885test # [ 0.780437] PM: Magic number: 14:89:94886test # [ 0.781948] RAS: Correctable Errors collector initialized.887second # [ 0.779191] clk: Disabling unused clocks888second # [ 0.780036] PM: genpd: Disabling unused power domains889test # [ 0.790033] clk: Disabling unused clocks890test # [ 0.790879] PM: genpd: Disabling unused power domains891test # [ 0.890026] Freeing initrd memory: 29620K892second # [ 0.885081] Freeing initrd memory: 29616K893test # [ 0.892883] Freeing unused decrypted memory: 2028K894second # [ 0.887848] Freeing unused decrypted memory: 2028K895test # [ 0.895454] Freeing unused kernel image (initmem) memory: 3660K896test # [ 0.896716] Write protecting the kernel read-only data: 32768k897second # [ 0.890380] Freeing unused kernel image (initmem) memory: 3660K898second # [ 0.891660] Write protecting the kernel read-only data: 32768k899test # [ 0.898127] Freeing unused kernel image (text/rodata gap) memory: 296K900second # [ 0.893077] Freeing unused kernel image (text/rodata gap) memory: 296K901test # [ 0.899752] Freeing unused kernel image (rodata/data gap) memory: 684K902second # [ 0.894711] Freeing unused kernel image (rodata/data gap) memory: 684K903test # [ 0.931467] x86/mm: Checked W+X mappings: passed, no W+X pages found.904test # [ 0.932744] Run /init as init process905second # [ 0.926381] x86/mm: Checked W+X mappings: passed, no W+X pages found.906second # [ 0.927694] Run /init as init process907test # [ 0.941624] systemd[1]: Inserted module 'autofs4'908second # [ 0.935921] systemd[1]: Inserted module 'autofs4'909test # [ 0.956297] fuse: init (API version 7.45)910second # [ 0.950376] fuse: init (API version 7.45)911test # [ 0.962012] ACPI: \_SB_.GSIG: Enabled at IRQ 22912second # [ 0.955931] ACPI: \_SB_.GSIG: Enabled at IRQ 22913test # [ 0.964539] ACPI: \_SB_.GSIH: Enabled at IRQ 23914second # [ 0.958487] ACPI: \_SB_.GSIH: Enabled at IRQ 23915test # [ 0.967692] ACPI: \_SB_.GSIE: Enabled at IRQ 20916second # [ 0.961781] ACPI: \_SB_.GSIE: Enabled at IRQ 20917test # [ 0.969866] ACPI: \_SB_.GSIF: Enabled at IRQ 21918second # [ 0.963955] ACPI: \_SB_.GSIF: Enabled at IRQ 21919test # [ 0.975173] virtiofs virtio5: discovered new tag: nix-store920second # [ 0.969052] virtiofs virtio5: discovered new tag: nix-store921test # [ 0.977160] virtiofs virtio5: virtio_fs_setup_dax: No cache capability922second # [ 0.971010] virtiofs virtio5: virtio_fs_setup_dax: No cache capability923second # [ 0.977595] virtiofs virtio6: discovered new tag: shared924test # [ 0.984435] virtiofs virtio6: discovered new tag: shared925second # [ 0.979528] virtiofs virtio6: virtio_fs_setup_dax: No cache capability926test # [ 0.986373] virtiofs virtio6: virtio_fs_setup_dax: No cache capability927second # [ 0.982753] virtiofs virtio7: discovered new tag: xchg928test # [ 0.989676] virtiofs virtio7: discovered new tag: xchg929second # [ 0.985021] virtiofs virtio7: virtio_fs_setup_dax: No cache capability930test # [ 0.991575] virtiofs virtio7: virtio_fs_setup_dax: No cache capability931second # [ 1.002730] systemd[1]: Successfully made /usr/ read-only.932test # [ 1.009639] systemd[1]: Successfully made /usr/ read-only.933second # [ 1.338855] 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.345620] 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)935second # [ 1.344540] systemd[1]: Detected virtualization kvm.936test # [ 1.351339] systemd[1]: Detected virtualization kvm.937second # [ 1.345572] systemd[1]: Detected architecture x86-64.938test # [ 1.352372] systemd[1]: Detected architecture x86-64.939second # [ 1.346623] systemd[1]: Running in initrd.940test # [ 1.353415] systemd[1]: Running in initrd.941second # [ 1.347665] systemd[1]: Initializing machine ID from random generator.942test # [ 1.354471] systemd[1]: Initializing machine ID from random generator.943second # [ 1.349062] systemd[1]: Hostname set to <second>.944test # [ 1.355817] systemd[1]: Hostname set to <test>.945second # [ 1.401051] systemd[1]: bpf-restrict-fs: LSM BPF program attached946test # [ 1.407805] systemd[1]: bpf-restrict-fs: LSM BPF program attached947second # [ 1.428721] systemd[1]: Queued start job for default target Initrd Default Target.948test # [ 1.435372] systemd[1]: Queued start job for default target Initrd Default Target.949test # [ 1.438465] systemd[1]: Created slice Slice /system/modprobe.950second # [ 1.432574] systemd[1]: Created slice Slice /system/modprobe.951test # [ 1.439819] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.952second # [ 1.433926] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.953test # [ 1.441440] systemd[1]: Expecting device /dev/disk/by-label/nixos...954second # [ 1.435556] systemd[1]: Expecting device /dev/disk/by-label/nixos...955test # [ 1.442749] systemd[1]: Reached target Path Units.956second # [ 1.436857] systemd[1]: Reached target Path Units.957test # [ 1.443798] systemd[1]: Reached target Slice Units.958second # [ 1.437903] systemd[1]: Reached target Slice Units.959test # [ 1.444847] systemd[1]: Reached target Swaps.960second # [ 1.438956] systemd[1]: Reached target Swaps.961test # [ 1.445809] systemd[1]: Reached target Timer Units.962second # [ 1.439923] systemd[1]: Reached target Timer Units.963test # [ 1.446934] systemd[1]: Listening on D-Bus System Message Bus Socket.964second # [ 1.441071] systemd[1]: Listening on D-Bus System Message Bus Socket.965test # [ 1.448372] systemd[1]: Listening on Journal Socket (/dev/log).966second # [ 1.442499] systemd[1]: Listening on Journal Socket (/dev/log).967test # [ 1.449722] systemd[1]: Listening on Journal Sockets.968second # [ 1.443839] systemd[1]: Listening on Journal Sockets.969test # [ 1.450893] systemd[1]: Listening on udev Control Socket.970second # [ 1.445005] systemd[1]: Listening on udev Control Socket.971test # [ 1.459803] systemd[1]: Listening on udev Kernel Socket.972second # [ 1.453897] systemd[1]: Listening on udev Kernel Socket.973test # [ 1.460939] systemd[1]: Reached target Socket Units.974second # [ 1.455038] systemd[1]: Reached target Socket Units.975test # [ 1.462642] systemd[1]: Starting Create List of Static Device Nodes...976second # [ 1.456728] systemd[1]: Starting Create List of Static Device Nodes...977test # [ 1.466614] systemd[1]: Starting Load Kernel Module configfs...978second # [ 1.460521] systemd[1]: Starting Load Kernel Module configfs...979test # [ 1.473520] systemd[1]: Starting Journal Service...980second # [ 1.468666] systemd[1]: Starting Journal Service...981test # [ 1.481398] systemd[1]: Starting Load Kernel Modules...982second # [ 1.476524] systemd[1]: Starting Load Kernel Modules...983test # [ 1.482940] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os984second # [ 1.478129] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os985test # [ 1.487926] systemd[1]: Starting Coldplug All udev Devices...986second # [ 1.484665] systemd[1]: Starting Coldplug All udev Devices...987test # [ 1.501420] systemd[1]: Finished Create List of Static Device Nodes.988second # [ 1.498795] systemd[1]: Finished Create List of Static Device Nodes.989test # [ 1.507902] systemd[1]: modprobe@configfs.service: Deactivated successfully.990test # [ 1.511220] systemd-journald[65]: Collecting audit messages is disabled.991second # [ 1.505032] systemd[1]: modprobe@configfs.service: Deactivated successfully.992second # [ 1.506486] systemd-journald[65]: Collecting audit messages is disabled.993test # [ 1.515782] systemd[1]: Finished Load Kernel Module configfs.994second # [ 1.512869] systemd[1]: Finished Load Kernel Module configfs.995test # [ 1.522790] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config996second # [ 1.517911] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config997test # [ 1.526730] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.998second # [ 1.521817] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.999test # [ 1.532979] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1000second # [ 1.528982] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1001test # [ 1.536368] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1002second # [ 1.530962] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1003test # [ 1.562207] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1004second # [ 1.558049] systemd[1]: Finished Load Kernel Modules.1005test # [ 1.569425] systemd[1]: Finished Load Kernel Modules.1006second # [ 1.565517] systemd[1]: Starting Apply Kernel Variables...1007test # [ 1.574407] systemd[1]: Starting Apply Kernel Variables...1008second # [ 1.574849] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1009test # [ 1.586509] systemd[1]: Starting Create Static Device Nodes in /dev...1010second # [ 1.586182] systemd[1]: Starting Create Static Device Nodes in /dev...1011test # [ 1.600970] systemd[1]: Finished Apply Kernel Variables.1012second # [ 1.596559] systemd[1]: Finished Apply Kernel Variables.1013test # [ 1.609960] systemd[1]: Finished Create Static Device Nodes in /dev.1014test # [ 1.614525] systemd[1]: Reached target Preparation for Local File Systems.1015second # [ 1.609070] systemd[1]: Finished Create Static Device Nodes in /dev.1016test # [ 1.619415] systemd[1]: Reached target Local File Systems.1017second # [ 1.613715] systemd[1]: Reached target Preparation for Local File Systems.1018second # [ 1.617531] systemd[1]: Reached target Local File Systems.1019test # [ 1.624394] systemd[1]: Starting Rule-based Manager for Device Events and Files...1020second # [ 1.624518] systemd[1]: Starting Rule-based Manager for Device Events and Files...1021second # [ 1.634870] systemd[1]: Started Journal Service.1022test # [ 1.642472] systemd[1]: Started Journal Service.1023second # [ 1.465910] systemd-modules-load[66]: Inserted module 'dm_mod'1024second # [ 1.469219] systemd-modules-load[66]: Inserted module 'virtio_balloon'1025test # [ 1.472740] systemd-modules-load[66]: Inserted module 'dm_mod'1026second # [ 1.470636] systemd-modules-load[66]: Inserted module 'virtio_gpu'1027test # [ 1.477269] systemd-modules-load[66]: Inserted module 'virtio_balloon'1028test # [ 1.478894] systemd-modules-load[66]: Inserted module 'virtio_gpu'1029second # [ 1.477223] systemd[1]: Starting Create System Files and Directories...1030test # [ 1.488075] systemd[1]: Starting Create System Files and Directories...1031second # [ 1.500244] systemd[1]: Finished Create System Files and Directories.1032test # [ 1.506983] systemd[1]: Finished Create System Files and Directories.1033test # [ 1.512320] systemd-udevd[73]: Using default interface naming scheme 'v261'.1034second # [ 1.507173] systemd-udevd[74]: Using default interface naming scheme 'v261'.1035test # [ 1.534070] systemd[1]: Started Rule-based Manager for Device Events and Files.1036second # [ 1.528630] systemd[1]: Started Rule-based Manager for Device Events and Files.1037second # [ 1.542700] systemd[1]: Finished Coldplug All udev Devices.1038test # [ 1.550151] systemd[1]: Finished Coldplug All udev Devices.1039second # [ 1.543737] systemd[1]: Reached target System Initialization.1040second # [ 1.544752] systemd[1]: Reached target Basic System.1041test # [ 1.552637] systemd[1]: Reached target System Initialization.1042test # [ 1.553644] systemd[1]: Reached target Basic System.1043second # [ 1.889349] virtio_blk virtio2: 1/0/0 default/read/poll queues1044second # [ 1.902574] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121045second # [ 1.905303] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1046second # [ 1.912648] ehci-pci 0000:00:1d.7: EHCI Host Controller1047second # [ 1.913510] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11048test # [ 1.920510] virtio_blk virtio2: 1/0/0 default/read/poll queues1049second # [ 1.914850] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001050test # [ 1.922882] ehci-pci 0000:00:1d.7: EHCI Host Controller1051test # [ 1.923709] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11052second # [ 1.917881] serio: i8042 KBD port at 0x60,0x64 irq 11053second # [ 1.918685] serio: i8042 AUX port at 0x60,0x64 irq 121054test # [ 1.925807] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001055second # [ 1.921481] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001056second # [ 1.922411] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181057test # [ 1.929686] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121058second # [ 1.923999] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11059test # [ 1.932155] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1060second # [ 1.927460] usb usb1: Product: EHCI Host Controller1061test # [ 1.934361] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001062second # [ 1.928207] usb usb1: Manufacturer: Linux 6.18.56 ehci_hcd1063second # [ 1.929464] usb usb1: SerialNumber: 0000:00:1d.71064test # [ 1.935300] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181065test # [ 1.938003] serio: i8042 KBD port at 0x60,0x64 irq 11066second # [ 1.932521] hub 1-0:1.0: USB hub found1067test # [ 1.938804] serio: i8042 AUX port at 0x60,0x64 irq 121068second # [ 1.933219] hub 1-0:1.0: 6 ports detected1069test # [ 1.939770] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11070test # [ 1.940994] usb usb1: Product: EHCI Host Controller1071test # [ 1.942167] usb usb1: Manufacturer: Linux 6.18.56 ehci_hcd1072second # [ 1.936469] uhci_hcd 0000:00:1d.0: UHCI Host Controller1073test # [ 1.943596] usb usb1: SerialNumber: 0000:00:1d.71074second # [ 1.937263] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21075test # [ 1.945369] hub 1-0:1.0: USB hub found1076test # [ 1.946860] hub 1-0:1.0: 6 ports detected1077second # [ 1.943980] uhci_hcd 0000:00:1d.0: detected 2 ports1078test # [ 1.951683] uhci_hcd 0000:00:1d.0: UHCI Host Controller1079test # [ 1.952525] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21080second # [ 1.949192] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801081test # [ 1.963688] uhci_hcd 0000:00:1d.0: detected 2 ports1082second # [ 1.958090] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181083second # [ 1.959335] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11084test # [ 1.971441] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801085test # [ 1.978204] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181086second # [ 1.975885] usb usb2: Product: UHCI Host Controller1087test # [ 1.985815] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11088second # [ 1.980846] usb usb2: Manufacturer: Linux 6.18.56 uhci_hcd1089second # [ 1.989493] usb usb2: SerialNumber: 0000:00:1d.01090test # [ 1.998113] usb usb2: Product: UHCI Host Controller1091test # [ 1.999023] usb usb2: Manufacturer: Linux 6.18.56 uhci_hcd1092second # [ 1.826253] systemd[1]: Starting Virtual Console Setup...1093test # [ 1.832850] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line.1094second # [ 1.998823] hub 2-0:1.0: USB hub found1095test # [ 1.836259] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line.1096second # [ 2.002189] SCSI subsystem initialized1097test # [ 1.841292] systemd[1]: Starting Virtual Console Setup...1098second # [ 2.006486] hub 2-0:1.0: 2 ports detected1099test # [ 2.013772] usb usb2: SerialNumber: 0000:00:1d.01100test # [ 2.018514] SCSI subsystem initialized1101test # [ 2.026006] hub 2-0:1.0: USB hub found1102second # [ 1.851843] systemd-vconsole-setup[97]: Configuration of first virtual console was skipped, ignoring remaining ones.1103second # [ 1.855069] systemd[1]: Finished Virtual Console Setup.1104test # [ 2.035560] hub 2-0:1.0: 2 ports detected1105second # [ 1.860535] (udev-worker)[88]: Network interface NamePolicy= disabled on kernel command line.1106test # [ 1.869270] systemd-vconsole-setup[98]: Configuration of first virtual console was skipped, ignoring remaining ones.1107second # [ 1.866519] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line.1108test # [ 1.873660] systemd[1]: Finished Virtual Console Setup.1109second # [ 2.038488] uhci_hcd 0000:00:1d.1: UHCI Host Controller1110second # [ 2.039286] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31111test # [ 2.050625] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01112second # [ 2.046290] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01113test # [ 2.053153] uhci_hcd 0000:00:1d.1: UHCI Host Controller1114test # [ 2.053993] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31115second # [ 2.050089] uhci_hcd 0000:00:1d.1: detected 2 ports1116second # [ 2.058641] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01117test # [ 2.067577] uhci_hcd 0000:00:1d.1: detected 2 ports1118second # [ 1.893073] systemd[1]: Found device /dev/disk/by-label/nixos.1119test # [ 2.069451] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01120second # [ 1.894848] systemd[1]: Reached target Initrd Root Device.1121second # [ 1.898069] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1122test # [ 2.074109] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181123test # [ 1.908520] systemd[1]: Found device /dev/disk/by-label/nixos.1124test # [ 1.912073] systemd[1]: Reached target Initrd Root Device.1125second # [ 2.074886] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181126test # [ 1.914078] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1127test # [ 2.084337] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11128second # [ 2.080229] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11129second # [ 2.081566] usb usb3: Product: UHCI Host Controller1130test # [ 2.088328] usb usb3: Product: UHCI Host Controller1131second # [ 2.082341] usb usb3: Manufacturer: Linux 6.18.56 uhci_hcd1132test # [ 2.089089] usb usb3: Manufacturer: Linux 6.18.56 uhci_hcd1133second # [ 2.084490] usb usb3: SerialNumber: 0000:00:1d.11134test # [ 2.091652] usb usb3: SerialNumber: 0000:00:1d.11135test # [ 2.093600] hub 3-0:1.0: USB hub found1136second # [ 2.087634] hub 3-0:1.0: USB hub found1137test # [ 2.097286] hub 3-0:1.0: 2 ports detected1138second # [ 2.091532] hub 3-0:1.0: 2 ports detected1139second # [ 1.925579] systemd-fsck[104]: nixos: clean, 12/65536 files, 13019/262144 blocks1140second # [ 2.096515] uhci_hcd 0000:00:1d.2: UHCI Host Controller1141second # [ 2.097336] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41142test # [ 2.104938] uhci_hcd 0000:00:1d.2: UHCI Host Controller1143test # [ 2.107449] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41144second # [ 1.933641] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1145test # [ 1.941390] systemd-fsck[104]: nixos: clean, 12/65536 files, 13019/262144 blocks1146second # [ 2.105528] uhci_hcd 0000:00:1d.2: detected 2 ports1147second # [ 2.106386] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01148test # [ 1.946647] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1149second # [ 2.109807] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181150test # [ 2.117377] uhci_hcd 0000:00:1d.2: detected 2 ports1151second # [ 2.111191] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11152test # [ 2.118230] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01153second # [ 2.113205] usb usb4: Product: UHCI Host Controller1154second # [ 2.114161] usb usb4: Manufacturer: Linux 6.18.56 uhci_hcd1155test # [ 2.121628] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181156test # [ 2.125377] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11157second # [ 2.120616] usb usb4: SerialNumber: 0000:00:1d.21158test # [ 2.127384] usb usb4: Product: UHCI Host Controller1159test # [ 2.128173] usb usb4: Manufacturer: Linux 6.18.56 uhci_hcd1160second # [ 2.123057] hub 4-0:1.0: USB hub found1161test # [ 2.130055] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1162test # [ 2.131369] usb usb4: SerialNumber: 0000:00:1d.21163second # [ 2.125382] hub 4-0:1.0: 2 ports detected1164test # [ 2.132821] hub 4-0:1.0: USB hub found1165test # [ 2.135533] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1166test # [ 2.136504] ahci 0000:00:1f.2: flags: 64bit ncq only1167test # [ 2.138213] hub 4-0:1.0: 2 ports detected1168test # [ 2.150971] scsi host0: ahci1169test # [ 2.151793] scsi host1: ahci1170second # [ 2.146317] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1171test # [ 2.155369] scsi host2: ahci1172test # [ 2.157921] scsi host3: ahci1173second # [ 2.151658] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1174second # [ 2.153095] ahci 0000:00:1f.2: flags: 64bit ncq only1175second # [ 2.156520] scsi host0: ahci1176test # [ 2.164068] scsi host4: ahci1177second # [ 2.157779] scsi host1: ahci1178second # [ 2.159514] scsi host2: ahci1179second # [ 2.160726] scsi host3: ahci1180test # [ 2.168143] scsi host5: ahci1181test # [ 2.168803] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11182second # [ 2.163318] scsi host4: ahci1183second # [ 2.164928] scsi host5: ahci1184second # [ 2.166061] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11185second # [ 2.167838] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11186test # [ 2.175347] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11187second # [ 2.169165] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11188second # [ 2.170548] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11189test # [ 2.176951] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11190second # [ 2.171796] usb 1-1: new high-speed USB device number 2 using ehci-pci1191test # [ 2.178274] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11192second # [ 2.173066] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11193test # [ 2.179630] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11194second # [ 2.174368] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11195test # [ 2.180944] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11196test # [ 2.188399] usb 1-1: new high-speed USB device number 2 using ehci-pci1197second # [ 2.301356] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001198second # [ 2.302603] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101199second # [ 2.303921] usb 1-1: Product: QEMU USB Tablet1200second # [ 2.304741] usb 1-1: Manufacturer: QEMU1201second # [ 2.305374] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11202test # [ 2.318250] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001203test # [ 2.319502] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101204test # [ 2.320821] usb 1-1: Product: QEMU USB Tablet1205test # [ 2.321632] usb 1-1: Manufacturer: QEMU1206second # [ 2.315878] hid: raw HID events driver (C) Jiri Kosina1207test # [ 2.322254] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11208test # [ 2.332856] hid: raw HID events driver (C) Jiri Kosina1209second # [ 2.480921] ata1: SATA link down (SStatus 0 SControl 300)1210test # [ 2.489238] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1211test # [ 2.490608] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001212test # [ 2.491664] ata3.00: applying bridge limits1213test # [ 2.492507] ata3.00: configured for UDMA/1001214test # [ 2.493562] ata6: SATA link down (SStatus 0 SControl 300)1215test # [ 2.494572] ata5: SATA link down (SStatus 0 SControl 300)1216second # [ 2.489091] ata2: SATA link down (SStatus 0 SControl 300)1217test # [ 2.495722] ata4: SATA link down (SStatus 0 SControl 300)1218second # [ 2.490411] ata4: SATA link down (SStatus 0 SControl 300)1219test # [ 2.497649] ata1: SATA link down (SStatus 0 SControl 300)1220second # [ 2.491414] ata6: SATA link down (SStatus 0 SControl 300)1221test # [ 2.498721] ata2: SATA link down (SStatus 0 SControl 300)1222second # [ 2.492415] ata5: SATA link down (SStatus 0 SControl 300)1223second # [ 2.493470] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1224test # [ 2.499846] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51225second # [ 2.494476] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001226second # [ 2.495318] ata3.00: applying bridge limits1227test # [ 2.333887] systemd[1]: Mounting /sysroot...1228second # [ 2.330067] systemd[1]: Mounting /sysroot...1229second # [ 2.499372] ata3.00: configured for UDMA/1001230second # [ 2.501771] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51231test # [ 2.549417] usbcore: registered new interface driver usbhid1232test # [ 2.550274] usbhid: USB HID core driver1233second # [ 2.544543] usbcore: registered new interface driver usbhid1234second # [ 2.545389] usbhid: USB HID core driver1235test # [ 2.566368] 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.568420] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01237second # [ 2.563009] 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/input21238test # [ 2.572521] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1239second # [ 2.566788] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01240test # [ 2.588442] cdrom: Uniform CD-ROM driver Revision: 3.201241test # [ 2.603024] EXT4-fs (vda): mounted filesystem 29b71b4d-e13e-4c52-8868-b72b2326d50a r/w with ordered data mode. Quota mode: none.1242second # [ 2.597694] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1243second # [ 2.599262] EXT4-fs (vda): mounted filesystem 2191330f-035f-4333-9c84-a2182456a2d2 r/w with ordered data mode. Quota mode: none.1244test # [ 2.439412] systemd[1]: Mounted /sysroot.1245second # [ 2.434383] systemd[1]: Mounted /sysroot.1246test # [ 2.440774] systemd[1]: Reached target Initrd Root File System.1247second # [ 2.435765] systemd[1]: Reached target Initrd Root File System.1248test # [ 2.442869] systemd[1]: Starting Mountpoints Configured in the Real Root...1249second # [ 2.437893] systemd[1]: Starting Mountpoints Configured in the Real Root...1250second # [ 2.609558] cdrom: Uniform CD-ROM driver Revision: 3.201251test # [ 2.454460] systemd-sysroot-fstab-check[137]: /sysroot should be mounted in the initrd, will request daemon-reload.1252second # [ 2.448626] systemd-sysroot-fstab-check[138]: /sysroot should be mounted in the initrd, will request daemon-reload.1253test # [ 2.457404] systemd[1]: Reload requested from client PID 137 ('systemd-sysroot') (unit initrd-parse-etc.service)...1254test # [ 2.459907] systemd[1]: Reloading...1255second # [ 2.453370] systemd[1]: Reload requested from client PID 138 ('systemd-sysroot') (unit initrd-parse-etc.service)...1256second # [ 2.456110] systemd[1]: Reloading...1257test # [ 2.518882] systemd[1]: Reloading finished in 59 ms.1258second # [ 2.513490] systemd[1]: Reloading finished in 58 ms.1259test # [ 2.526350] systemd-sysroot-fstab-check[137]: Requesting initrd-fs.target/start/replace...1260second # [ 2.522293] systemd-sysroot-fstab-check[138]: Requesting initrd-fs.target/start/replace...1261test # [ 2.528882] systemd-sysroot-fstab-check[137]: Requesting swap.target/start/replace...1262test # [ 2.533775] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1263second # [ 2.527337] systemd-sysroot-fstab-check[138]: Requesting swap.target/start/replace...1264test # [ 2.535466] systemd[1]: Finished Mountpoints Configured in the Real Root.1265test # [ 2.536673] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1266second # [ 2.530899] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1267second # [ 2.533844] systemd[1]: Finished Mountpoints Configured in the Real Root.1268second # [ 2.534978] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1269test # [ 3.329873] systemd[1]: Mounting /sysroot/nix/.ro-store...1270test # [ 3.333081] systemd[1]: Mounting /sysroot/nix/.rw-store...1271second # [ 3.330079] systemd[1]: Mounting /sysroot/nix/.ro-store...1272test # [ 3.337141] systemd[1]: Mounting /sysroot/run...1273test # [ 3.340081] systemd[1]: Mounting /sysroot/tmp/shared...1274second # [ 3.333683] systemd[1]: Mounting /sysroot/nix/.rw-store...1275second # [ 3.335695] systemd[1]: Mounting /sysroot/run...1276test # [ 3.347149] systemd[1]: Mounting /sysroot/tmp/xchg...1277second # [ 3.341079] systemd[1]: Mounting /sysroot/tmp/shared...1278second # [ 3.347284] systemd[1]: Mounting /sysroot/tmp/xchg...1279test # [ 3.364576] systemd[1]: Mounted /sysroot/nix/.ro-store.1280test # [ 3.367361] systemd[1]: Mounted /sysroot/nix/.rw-store.1281second # [ 3.362984] systemd[1]: Mounted /sysroot/nix/.ro-store.1282test # [ 3.369777] systemd[1]: Mounted /sysroot/run.1283test # [ 3.371364] systemd[1]: Mounted /sysroot/tmp/shared.1284second # [ 3.365310] systemd[1]: Mounted /sysroot/nix/.rw-store.1285test # [ 3.375087] systemd[1]: Mounted /sysroot/tmp/xchg.1286second # [ 3.369812] systemd[1]: Mounted /sysroot/run.1287test # [ 3.378422] systemd[1]: Starting rw-sysroot-nix-store.service...1288second # [ 3.373098] systemd[1]: Mounted /sysroot/tmp/shared.1289second # [ 3.374127] systemd[1]: Mounted /sysroot/tmp/xchg.1290second # [ 3.377905] systemd[1]: Starting rw-sysroot-nix-store.service...1291test # [ 3.387309] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1292test # [ 3.389262] systemd[1]: Finished rw-sysroot-nix-store.service.1293test # [ 3.391521] systemd[1]: Mounting /sysroot/nix/store...1294second # [ 3.387210] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1295second # [ 3.389149] systemd[1]: Finished rw-sysroot-nix-store.service.1296second # [ 3.391543] systemd[1]: Mounting /sysroot/nix/store...1297test # [ 3.409087] systemd[1]: Mounted /sysroot/nix/store.1298test # [ 3.410820] systemd[1]: Reached target Initrd File Systems.1299test # [ 3.412439] systemd[1]: Starting Find NixOS closure...1300second # [ 3.408290] systemd[1]: Mounted /sysroot/nix/store.1301second # [ 3.409682] systemd[1]: Reached target Initrd File Systems.1302test # [ 3.416146] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1303second # [ 3.411483] systemd[1]: Starting Find NixOS closure...1304second # [ 3.415261] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1305test # [ 3.431590] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1306test # [ 3.435872] systemd[1]: Finished Find NixOS closure.1307test # [ 3.437400] systemd[1]: Reached target Initrd Default Target.1308second # [ 3.431592] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1309test # [ 3.439435] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1310second # [ 3.434731] systemd[1]: Finished Find NixOS closure.1311second # [ 3.436407] systemd[1]: Reached target Initrd Default Target.1312second # [ 3.438612] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1313test # [ 3.450981] systemd[1]: Stopped target Initrd Default Target.1314test # [ 3.452634] systemd[1]: Stopped target Basic System.1315test # [ 3.453944] systemd[1]: Stopped target Initrd Root Device.1316test # [ 3.456154] systemd[1]: Stopped target Path Units.1317second # [ 3.450105] systemd[1]: Stopped target Initrd Default Target.1318test # [ 3.457138] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1319second # [ 3.451686] systemd[1]: Stopped target Basic System.1320test # [ 3.458620] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1321test # [ 3.460067] systemd[1]: Stopped target Slice Units.1322second # [ 3.454168] systemd[1]: Stopped target Initrd Root Device.1323test # [ 3.461239] systemd[1]: Stopped target Socket Units.1324second # [ 3.455226] systemd[1]: Stopped target Path Units.1325test # [ 3.462688] systemd[1]: Stopped target System Initialization.1326second # [ 3.456204] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1327test # [ 3.464189] systemd[1]: Stopped target Swaps.1328second # [ 3.457714] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1329test # [ 3.465231] systemd[1]: Stopped target Timer Units.1330second # [ 3.459152] systemd[1]: Stopped target Slice Units.1331second # [ 3.460154] systemd[1]: Stopped target Socket Units.1332test # [ 3.466590] systemd[1]: dbus.socket: Deactivated successfully.1333second # [ 3.461176] systemd[1]: Stopped target System Initialization.1334test # [ 3.467802] systemd[1]: Closed D-Bus System Message Bus Socket.1335second # [ 3.463090] systemd[1]: Stopped target Swaps.1336second # [ 3.463987] systemd[1]: Stopped target Timer Units.1337test # [ 3.470170] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1338test # [ 3.471526] systemd[1]: Stopped Find NixOS closure.1339second # [ 3.465214] systemd[1]: dbus.socket: Deactivated successfully.1340test # [ 3.472492] systemd[1]: Starting rw-sysroot-nix-store.service...1341second # [ 3.467151] systemd[1]: Closed D-Bus System Message Bus Socket.1342test # [ 3.473669] systemd[1]: systemd-sysctl.service: Deactivated successfully.1343second # [ 3.468291] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1344test # [ 3.475231] systemd[1]: Stopped Apply Kernel Variables.1345second # [ 3.469660] systemd[1]: Stopped Find NixOS closure.1346test # [ 3.476533] systemd[1]: systemd-modules-load.service: Deactivated successfully.1347second # [ 3.470745] systemd[1]: Starting rw-sysroot-nix-store.service...1348test # [ 3.478222] systemd[1]: Stopped Load Kernel Modules.1349second # [ 3.473164] systemd[1]: systemd-sysctl.service: Deactivated successfully.1350second # [ 3.474435] systemd[1]: Stopped Apply Kernel Variables.1351test # [ 3.479994] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1352second # [ 3.475453] systemd[1]: systemd-modules-load.service: Deactivated successfully.1353test # [ 3.482251] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1354second # [ 3.476888] systemd[1]: Stopped Load Kernel Modules.1355test # [ 3.483643] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1356second # [ 3.477886] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1357test # [ 3.484945] systemd[1]: Stopped Create System Files and Directories.1358second # [ 3.480124] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1359test # [ 3.487345] systemd[1]: Stopped target Local File Systems.1360second # [ 3.481512] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1361test # [ 3.488684] systemd[1]: Stopped target Preparation for Local File Systems.1362second # [ 3.482831] systemd[1]: Stopped Create System Files and Directories.1363second # [ 3.484032] systemd[1]: Stopped target Local File Systems.1364test # [ 3.490476] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1365second # [ 3.486315] systemd[1]: Stopped target Preparation for Local File Systems.1366test # [ 3.494270] systemd[1]: Stopped Coldplug All udev Devices.1367second # [ 3.488418] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1368test # [ 3.495390] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1369test # [ 3.496876] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1370second # [ 3.490767] systemd[1]: Stopped Coldplug All udev Devices.1371second # [ 3.493172] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1372test # [ 3.500167] systemd[1]: Stopped Virtual Console Setup.1373second # [ 3.494594] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1374test # [ 3.503303] systemd[1]: initrd-cleanup.service: Deactivated successfully.1375second # [ 3.498375] systemd[1]: Stopped Virtual Console Setup.1376test # [ 3.507908] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1377second # [ 3.502531] systemd[1]: initrd-cleanup.service: Deactivated successfully.1378test # [ 3.509980] systemd[1]: systemd-udevd.service: Deactivated successfully.1379test # [ 3.511867] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1380second # [ 3.506815] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1381test # [ 3.513786] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1382second # [ 3.508894] systemd[1]: systemd-udevd.service: Deactivated successfully.1383test # [ 3.516125] systemd[1]: Finished rw-sysroot-nix-store.service.1384second # [ 3.510712] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1385test # [ 3.518403] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1386second # [ 3.512942] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1387test # [ 3.520188] systemd[1]: Closed udev Control Socket.1388second # [ 3.514422] systemd[1]: Finished rw-sysroot-nix-store.service.1389test # [ 3.521615] systemd[1]: Starting Cleanup udev Database...1390second # [ 3.516503] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1391test # [ 3.523188] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1392second # [ 3.518104] systemd[1]: Closed udev Control Socket.1393test # [ 3.524591] systemd[1]: Stopped Create Static Device Nodes in /dev.1394second # [ 3.519789] systemd[1]: Starting Cleanup udev Database...1395test # [ 3.526177] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1396second # [ 3.520920] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1397test # [ 3.527656] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1398second # [ 3.523754] systemd[1]: Stopped Create Static Device Nodes in /dev.1399test # [ 3.530154] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1400test # [ 3.531421] systemd[1]: Stopped Create List of Static Device Nodes.1401second # [ 3.524968] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1402second # [ 3.526456] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1403second # [ 3.527787] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1404second # [ 3.530162] systemd[1]: Stopped Create List of Static Device Nodes.1405test # [ 3.543421] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1406test # [ 3.545516] systemd[1]: Finished Cleanup udev Database.1407test # [ 3.547271] systemd[1]: Reached target Switch Root.1408second # [ 3.540465] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1409test # [ 3.548968] systemd[1]: Starting NixOS Activation...1410second # [ 3.543699] systemd[1]: Finished Cleanup udev Database.1411second # [ 3.544745] systemd[1]: Reached target Switch Root.1412second # [ 3.547425] systemd[1]: Starting NixOS Activation...1413test # [ 3.596814] initrd-nixos-activation-start[189]: booting system configuration /nix/store/5bryzfaan22xzg608dhvwpbk9adxk9im-nixos-system-test-test1414second # [ 3.593615] initrd-nixos-activation-start[190]: booting system configuration /nix/store/367gaijfmb1k89m7r75wcjr725dfd2jk-nixos-system-second-test1415test # [ 3.617829] initrd-nixos-activation-start[189]: running activation script...1416second # [ 3.615227] initrd-nixos-activation-start[190]: running activation script...1417test # [ 3.752636] initrd-nixos-activation-start[212]: setting up /etc...1418second # [ 3.755305] initrd-nixos-activation-start[213]: setting up /etc...1419test # [ 3.817948] initrd-nixos-activation-start[212]: setting up age secrets...1420second # [ 3.820980] initrd-nixos-activation-start[213]: setting up age secrets...1421second # [ 3.884647] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1422second # [ 3.886101] systemd[1]: Finished NixOS Activation.1423second # [ 3.888054] systemd[1]: Starting Switch Root...1424second # [ 3.898897] systemd[1]: Switching root.1425test # [ 3.906839] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1426test # [ 3.908663] systemd[1]: Finished NixOS Activation.1427test # [ 3.910599] systemd[1]: Starting Switch Root...1428test # [ 3.919838] systemd[1]: Switching root.1429second # [ 4.189285] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).1430test # [ 4.213196] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).1431test # [ 4.271228] NET: Registered PF_VSOCK protocol family1432second # [ 4.272723] NET: Registered PF_VSOCK protocol family1433test # [ 4.622126] 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)1434test # [ 4.626745] systemd[1]: Detected virtualization kvm.1435test # [ 4.627618] systemd[1]: Detected architecture x86-64.1436test # [ 4.628521] systemd[1]: Detected first boot.1437test # [ 4.629996] systemd[1]: Initializing machine ID from random generator.1438second # [ 4.623494] 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)1439second # [ 4.628072] systemd[1]: Detected virtualization kvm.1440second # [ 4.628951] systemd[1]: Detected architecture x86-64.1441second # [ 4.629867] systemd[1]: Detected first boot.1442second # [ 4.631297] systemd[1]: Initializing machine ID from random generator.1443test # [ 4.695096] systemd[1]: bpf-restrict-fs: LSM BPF program attached1444second # [ 4.695615] systemd[1]: bpf-restrict-fs: LSM BPF program attached1445test # [ 4.751195] systemd[1]: Applying preset policy.1446second # [ 4.751555] systemd[1]: Applying preset policy.1447test # [ 4.894214] systemd[1]: Populated /etc with preset unit settings.1448second # [ 4.893630] systemd[1]: Populated /etc with preset unit settings.1449test # [ 5.057452] systemd[1]: initrd-switch-root.service: Deactivated successfully.1450test # [ 5.058975] systemd[1]: Stopped initrd-switch-root.service.1451second # [ 5.054271] systemd[1]: initrd-switch-root.service: Deactivated successfully.1452test # [ 5.061259] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1453second # [ 5.055902] systemd[1]: Stopped initrd-switch-root.service.1454test # [ 5.063535] systemd[1]: Created slice Slice /system/getty.1455test # [ 5.064841] systemd[1]: Created slice User and Session Slice.1456second # [ 5.058186] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1457test # [ 5.065868] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1458second # [ 5.060377] systemd[1]: Created slice Slice /system/getty.1459test # [ 5.067172] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1460second # [ 5.061738] systemd[1]: Created slice User and Session Slice.1461test # [ 5.068404] systemd[1]: Expecting device /dev/hvc0...1462test # [ 5.069191] systemd[1]: Expecting device /dev/ttyS0...1463second # [ 5.062770] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1464test # [ 5.070080] systemd[1]: Reached target Local Encrypted Volumes.1465second # [ 5.064084] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1466test # [ 5.071042] systemd[1]: Stopped target initrd-fs.target.1467second # [ 5.065279] systemd[1]: Expecting device /dev/hvc0...1468second # [ 5.066117] systemd[1]: Expecting device /dev/ttyS0...1469second # [ 5.067018] systemd[1]: Reached target Local Encrypted Volumes.1470second # [ 5.067984] systemd[1]: Stopped target initrd-fs.target.1471test # [ 5.071925] systemd[1]: Stopped target initrd-root-fs.target.1472test # [ 5.080603] systemd[1]: Stopped target initrd-switch-root.target.1473test # [ 5.081595] systemd[1]: Reached target Virtual Machines and Containers.1474test # [ 5.082635] systemd[1]: Reached target Path Units.1475second # [ 5.068859] systemd[1]: Stopped target initrd-root-fs.target.1476test # [ 5.083457] systemd[1]: Reached target Remote File Systems.1477second # [ 5.077475] systemd[1]: Stopped target initrd-switch-root.target.1478test # [ 5.084376] systemd[1]: Reached target Slice Units.1479second # [ 5.078464] systemd[1]: Reached target Virtual Machines and Containers.1480test # [ 5.085184] systemd[1]: Reached target Swaps.1481second # [ 5.079508] systemd[1]: Reached target Path Units.1482second # [ 5.080299] systemd[1]: Reached target Remote File Systems.1483test # [ 5.086746] systemd[1]: Listening on Query the User Interactively for a Password.1484second # [ 5.081209] systemd[1]: Reached target Slice Units.1485second # [ 5.082038] systemd[1]: Reached target Swaps.1486test # [ 5.088915] systemd[1]: Listening on Process Core Dump Socket.1487second # [ 5.083584] systemd[1]: Listening on Query the User Interactively for a Password.1488test # [ 5.090608] systemd[1]: Listening on Credential Encryption/Decryption.1489second # [ 5.085745] systemd[1]: Listening on Process Core Dump Socket.1490test # [ 5.092390] systemd[1]: Listening on Factory Reset Management.1491test # [ 5.093428] systemd[1]: Listening on Hostname Service Socket.1492second # [ 5.087388] systemd[1]: Listening on Credential Encryption/Decryption.1493second # [ 5.089173] systemd[1]: Listening on Factory Reset Management.1494test # [ 5.095711] systemd[1]: Starting Journal Log Access Socket...1495second # [ 5.090199] systemd[1]: Listening on Hostname Service Socket.1496test # [ 5.097020] systemd[1]: Listening on Journal Audit Socket.1497second # [ 5.092623] systemd[1]: Starting Journal Log Access Socket...1498test # [ 5.099318] systemd[1]: Listening on Console Output Muting Service Socket.1499second # [ 5.093770] systemd[1]: Listening on Journal Audit Socket.1500test # [ 5.100528] systemd[1]: Listening on Network Management Metrics Varlink Socket.1501test # [ 5.101834] systemd[1]: Listening on Network Management Varlink Socket.1502second # [ 5.095963] systemd[1]: Listening on Console Output Muting Service Socket.1503test # [ 5.102989] systemd[1]: Listening on Network Management Netlink Socket.1504second # [ 5.097170] systemd[1]: Listening on Network Management Metrics Varlink Socket.1505test # [ 5.104159] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1506second # [ 5.098512] systemd[1]: Listening on Network Management Varlink Socket.1507test # [ 5.105405] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1508second # [ 5.099664] systemd[1]: Listening on Network Management Netlink Socket.1509test # [ 5.106847] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1510second # [ 5.100832] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1511second # [ 5.102048] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1512second # [ 5.103476] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1513test # [ 5.110385] systemd[1]: Listening on Disk Repartitioning Service Socket.1514test # [ 5.111536] systemd[1]: Listening on Resolve Monitor Varlink Socket.1515test # [ 5.112633] systemd[1]: Listening on Resolve Service Varlink Socket.1516second # [ 5.106969] systemd[1]: Listening on Disk Repartitioning Service Socket.1517test # [ 5.113717] systemd[1]: Listening on udev Control Socket.1518second # [ 5.108126] systemd[1]: Listening on Resolve Monitor Varlink Socket.1519test # [ 5.114705] systemd[1]: Listening on udev Varlink Socket.1520second # [ 5.109215] systemd[1]: Listening on Resolve Service Varlink Socket.1521test # [ 5.116587] systemd[1]: Mounting Huge Pages File System...1522second # [ 5.110305] systemd[1]: Listening on udev Control Socket.1523second # [ 5.111288] systemd[1]: Listening on udev Varlink Socket.1524second # [ 5.113136] systemd[1]: Mounting Huge Pages File System...1525test # [ 5.119380] systemd[1]: Mounting POSIX Message Queue File System...1526second # [ 5.115789] systemd[1]: Mounting POSIX Message Queue File System...1527test # [ 5.126413] systemd[1]: Mounting Kernel Debug File System...1528second # [ 5.121675] systemd[1]: Mounting Kernel Debug File System...1529test # [ 5.132305] systemd[1]: Mounting Kernel Trace File System...1530second # [ 5.126917] systemd[1]: Mounting Kernel Trace File System...1531test # [ 5.142175] systemd[1]: Starting Create List of Static Device Nodes...1532second # [ 5.136185] systemd[1]: Starting Create List of Static Device Nodes...1533second # [ 5.147701] systemd[1]: Starting Load Kernel Module configfs...1534test # [ 5.155929] systemd[1]: Starting Load Kernel Module configfs...1535second # [ 5.155313] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1536test # [ 5.164803] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1537test # [ 5.168779] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1538test # [ 5.170698] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1539second # [ 5.165519] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1540test # [ 5.173380] systemd[1]: Mounting FUSE Control File System...1541second # [ 5.169365] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1542test # [ 5.176355] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671543second # [ 5.173176] systemd[1]: Mounting FUSE Control File System...1544second # [ 5.175521] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671545test # [ 5.183857] systemd[1]: Starting Journal Service...1546second # [ 5.184590] systemd[1]: Starting Journal Service...1547test # [ 5.193399] systemd[1]: Starting Load Kernel Modules...1548second # [ 5.191239] systemd[1]: Starting Load Kernel Modules...1549test # [ 5.201674] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1550second # [ 5.200461] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1551test # [ 5.209819] systemd[1]: Starting Remount Root and Kernel File Systems...1552second # [ 5.206124] systemd[1]: Starting Remount Root and Kernel File Systems...1553test # [ 5.212953] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1554second # [ 5.208592] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1555second # [ 5.217641] systemd[1]: Starting Coldplug All udev Devices...1556test # [ 5.225469] systemd[1]: Starting Coldplug All udev Devices...1557test # [ 5.227228] systemd-journald[336]: Collecting audit messages is enabled.1558second # [ 5.221611] systemd-journald[316]: Collecting audit messages is enabled.1559test # [ 5.235059] systemd[1]: Listening on Journal Log Access Socket.1560second # [ 5.231715] systemd[1]: Listening on Journal Log Access Socket.1561test # [ 5.240686] systemd[1]: Mounted Huge Pages File System.1562second # [ 5.236642] systemd[1]: Mounted Huge Pages File System.1563second # [ 5.241661] systemd[1]: Mounted POSIX Message Queue File System.1564test # [ 5.249831] systemd[1]: Mounted POSIX Message Queue File System.1565second # [ 5.246594] systemd[1]: Mounted Kernel Debug File System.1566second # [ 5.249028] loop: module loaded1567test # [ 5.255557] loop: module loaded1568test # [ 5.258518] systemd[1]: Mounted Kernel Debug File System.1569second # [ 5.253623] systemd[1]: Mounted Kernel Trace File System.1570test # [ 5.091736] systemd[1]: Queued start job for default target Multi-User System.1571second # [ 5.088360] systemd[1]: Queued start job for default target Multi-User System.1572test # [ 5.095368] systemd[1]: systemd-journald.service: Deactivated successfully.1573test # [ 5.097612] systemd-modules-load[337]: Module 'ctr' is built in1574second # [ 5.091207] systemd[1]: systemd-journald.service: Deactivated successfully.1575test # [ 5.268197] systemd[1]: Started Journal Service.1576second # [ 5.261973] systemd[1]: Started Journal Service.1577second # [ 5.094389] systemd-modules-load[317]: Module 'ctr' is built in1578second # [ 5.095976] systemd-modules-load[317]: Inserted module 'loop'1579test # [ 5.102931] systemd-modules-load[337]: Inserted module 'loop'1580test # [ 5.277357] EXT4-fs (vda): re-mounted 29b71b4d-e13e-4c52-8868-b72b2326d50a.1581second # [ 5.271564] EXT4-fs (vda): re-mounted 2191330f-035f-4333-9c84-a2182456a2d2.1582second # [ 5.106075] systemd[1]: Finished Create List of Static Device Nodes.1583test # [ 5.113227] systemd[1]: Mounted Kernel Trace File System.1584second # [ 5.107334] systemd[1]: modprobe@configfs.service: Deactivated successfully.1585test # [ 5.114443] systemd[1]: Finished Create List of Static Device Nodes.1586second # [ 5.109086] systemd[1]: Finished Load Kernel Module configfs.1587test # [ 5.116571] systemd[1]: modprobe@configfs.service: Deactivated successfully.1588second # [ 5.111139] systemd[1]: Mounted FUSE Control File System.1589test # [ 5.119083] systemd[1]: Finished Load Kernel Module configfs.1590test # [ 5.120187] systemd[1]: Mounted FUSE Control File System.1591second # [ 5.114878] systemd[1]: Finished Load Kernel Modules.1592test # [ 5.125229] systemd[1]: Finished Load Kernel Modules.1593second # [ 5.119195] systemd[1]: Finished Remount Root and Kernel File Systems.1594test # [ 5.129916] systemd[1]: Finished Remount Root and Kernel File Systems.1595second # [ 5.128967] systemd[1]: Listening on Disk Image Download Service Socket.1596test # [ 5.135958] systemd[1]: Listening on Disk Image Download Service Socket.1597second # [ 5.132863] systemd[1]: Mounting Kernel Configuration File System...1598second # [ 5.135452] systemd[1]: Starting Firewall...1599test # [ 5.142392] systemd-oomd[339]: No swap; memory pressure usage will be degraded1600test # [ 5.144355] systemd[1]: Mounting Kernel Configuration File System...1601second # [ 5.138345] systemd[1]: Starting Flush Journal to Persistent Storage...1602second # [ 5.139479] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1603test # [ 5.148083] systemd[1]: Starting Firewall...1604second # [ 5.141765] systemd-oomd[319]: No swap; memory pressure usage will be degraded1605test # [ 5.151077] systemd[1]: Starting Flush Journal to Persistent Storage...1606test # [ 5.152208] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1607second # [ 5.147966] systemd[1]: Starting Load/Save OS Random Seed...1608test # [ 5.163831] systemd[1]: Starting Load/Save OS Random Seed...1609second # [ 5.158933] systemd[1]: Starting Apply Kernel Variables...1610second # [ 5.169691] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1611second # [ 5.170937] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1612test # [ 5.178918] systemd[1]: Starting Apply Kernel Variables...1613second # [ 5.173520] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1614test # [ 5.182288] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1615test # [ 5.183557] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1616test # [ 5.190218] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1617test # [ 5.384001] systemd-journald[336]: Received client request to flush runtime journal.1618second # [ 5.384830] systemd-journald[316]: Received client request to flush runtime journal.1619test # [ 5.396195] systemd[1]: Finished Load/Save OS Random Seed.1620test # [ 5.398597] systemd[1]: Reached target First Boot Complete.1621second # [ 5.393608] systemd[1]: Finished Load/Save OS Random Seed.1622test # [ 5.401366] systemd[1]: Mounted Kernel Configuration File System.1623test # [ 5.403322] systemd[1]: Finished Apply Kernel Variables.1624second # [ 5.396901] systemd[1]: Reached target First Boot Complete.1625test # [ 5.405249] systemd[1]: Starting Network Name Resolution...1626second # [ 5.399447] systemd[1]: Mounted Kernel Configuration File System.1627test # [ 5.406318] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1628test # [ 5.407878] systemd[1]: Starting Create Static Device Nodes in /dev...1629second # [ 5.403108] systemd[1]: Finished Apply Kernel Variables.1630test # [ 5.409818] systemd[1]: Finished Flush Journal to Persistent Storage.1631second # [ 5.404355] systemd[1]: Starting Network Name Resolution...1632second # [ 5.405347] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1633second # [ 5.407728] systemd[1]: Starting Create Static Device Nodes in /dev...1634test # [ 5.416541] systemd-resolved[365]: Positive Trust Anchors:1635second # [ 5.410287] systemd-resolved[345]: Positive Trust Anchors:1636test # [ 5.417702] systemd-resolved[365]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1637second # [ 5.411579] systemd-resolved[345]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1638second # [ 5.414749] systemd-resolved[345]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161639test # [ 5.421222] systemd-resolved[365]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161640test # [ 5.423745] systemd-resolved[365]: 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 test1641second # [ 5.417911] 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 test1642second # [ 5.432210] systemd[1]: Finished Flush Journal to Persistent Storage.1643second # [ 5.433333] systemd[1]: Finished Create Static Device Nodes in /dev.1644second # [ 5.435156] systemd[1]: Reached target Preparation for Local File Systems.1645second # [ 5.437370] systemd[1]: Starting Rule-based Manager for Device Events and Files...1646test # [ 5.446166] systemd[1]: Finished Create Static Device Nodes in /dev.1647test # [ 5.447282] systemd[1]: Reached target Preparation for Local File Systems.1648test # [ 5.450794] systemd[1]: Starting Rule-based Manager for Device Events and Files...1649second # [ 5.475526] systemd-resolved[345]: Using system hostname 'second'.1650test # [ 5.486167] systemd[1]: Finished Coldplug All udev Devices.1651second # [ 5.481215] systemd[1]: Started Network Name Resolution.1652test # [ 5.497077] systemd-resolved[365]: Using system hostname 'test'.1653second # [ 5.493149] systemd[1]: Finished Coldplug All udev Devices.1654test # [ 5.499828] systemd[1]: Started Network Name Resolution.1655test # [ 5.510774] systemd-udevd[379]: Using default interface naming scheme 'v261'.1656second # [ 5.506853] systemd-udevd[360]: Using default interface naming scheme 'v261'.1657test # [ 5.557087] systemd[1]: Started Rule-based Manager for Device Events and Files.1658second # [ 5.551197] systemd[1]: Started Rule-based Manager for Device Events and Files.1659test # [ 5.667747] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1660second # [ 5.667571] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1661second # [ 5.737915] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1662test # [ 5.744903] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1663second # [ 5.755888] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1664test # [ 5.768964] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1665test # [ 5.783673] (udev-worker)[406]: Network interface NamePolicy= disabled on kernel command line.1666second # [ 5.783354] (udev-worker)[388]: Network interface NamePolicy= disabled on kernel command line.1667second # [ 5.786071] (udev-worker)[389]: Network interface NamePolicy= disabled on kernel command line.1668test # [ 5.792874] (udev-worker)[409]: Network interface NamePolicy= disabled on kernel command line.1669test # [ 5.893085] systemd[1]: Mounting /run/wrappers...1670second # [ 5.890112] systemd[1]: Mounting /run/wrappers...1671test # [ 5.924938] systemd[1]: Mounted /run/wrappers.1672test # [ 5.926505] systemd[1]: Reached target Local File Systems.1673second # [ 5.920489] systemd[1]: Mounted /run/wrappers.1674second # [ 5.922100] systemd[1]: Reached target Local File Systems.1675test # [ 5.930139] systemd[1]: Listening on Boot Loader Control Service Socket.1676second # [ 5.925585] systemd[1]: Listening on Boot Loader Control Service Socket.1677test # [ 5.933734] systemd[1]: Starting register-nix-paths.service...1678second # [ 5.929125] systemd[1]: Starting register-nix-paths.service...1679test # [ 5.937105] systemd[1]: Starting Create SUID/SGID Wrappers...1680test # [ 5.938226] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1681second # [ 5.933422] systemd[1]: Starting Create SUID/SGID Wrappers...1682second # [ 5.934424] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1683test # [ 5.946294] systemd[1]: Starting Save Transient machine-id to Disk...1684second # [ 5.939825] systemd[1]: Starting Save Transient machine-id to Disk...1685second # [ 5.948994] systemd[1]: Starting Create System Files and Directories...1686test # [ 5.956754] systemd[1]: Starting Create System Files and Directories...1687test # [ 6.011904] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1688second # [ 6.006199] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1689test # [ 6.020687] systemd[1]: Finished Save Transient machine-id to Disk.1690second # [ 6.016089] systemd[1]: Finished Save Transient machine-id to Disk.1691test # [ 6.057355] systemd[1]: Finished Create System Files and Directories.1692second # [ 6.057472] systemd[1]: Finished Create System Files and Directories.1693test # [ 6.070161] systemd[1]: Starting Rebuild Journal Catalog...1694second # [ 6.065744] systemd[1]: Starting Rebuild Journal Catalog...1695second # [ 6.072151] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1696test # [ 6.085087] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1697second # [ 6.286501] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31698test # [ 6.309619] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input41699test # [ 6.143841] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1700second # [ 6.141525] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1701test # [ 6.151109] systemd[1]: Finished Firewall.1702test # [ 6.322098] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1703test # [ 6.156850] systemd[1]: Reached target Preparation for Network.1704test # [ 6.157919] systemd[1]: Listening on Network Management Resolve Hook Socket.1705test # [ 6.167584] systemd[1]: Starting Network Management...1706second # [ 6.163705] systemd[1]: Finished Firewall.1707second # [ 6.167446] systemd[1]: Finished Rebuild Journal Catalog.1708second # [ 6.169503] systemd[1]: Reached target Preparation for Network.1709second # [ 6.171960] systemd[1]: Listening on Network Management Resolve Hook Socket.1710test # [ 6.180996] systemd[1]: Finished Rebuild Journal Catalog.1711second # [ 6.179167] systemd[1]: Starting Network Management...1712test # [ 6.190598] systemd[1]: Starting Update is Completed...1713second # [ 6.184296] systemd[1]: Starting Update is Completed...1714second # [ 6.355747] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1715second # [ 6.360013] mousedev: PS/2 mouse device common for all mice1716second # [ 6.382497] ACPI: button: Power Button [PWRF]1717second # [ 6.251215] systemd[1]: Finished Update is Completed.1718test # [ 6.261657] systemd[1]: Finished Update is Completed.1719test # [ 6.340121] systemd-resolved[365]: Clock change detected. Flushing caches.1720second # [ 6.354897] systemd-resolved[345]: Clock change detected. Flushing caches.1721test # [ 6.367023] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input31722second # [ 6.485365] rtc_cmos PNP0B00:00: RTC can wake from S41723second # [ 6.518304] rtc_cmos PNP0B00:00: registered as rtc01724test # [ 6.381211] mousedev: PS/2 mouse device common for all mice1725test # [ 6.399045] ACPI: button: Power Button [PWRF]1726test # [ 6.473858] rtc_cmos PNP0B00:00: RTC can wake from S41727second # [ 6.521101] rtc_cmos PNP0B00:00: setting system clock to 2026-10-19T20:04:20 UTC (1792440260)1728second # [ 6.521190] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1729test # [ 6.507498] rtc_cmos PNP0B00:00: registered as rtc01730test # [ 6.507593] rtc_cmos PNP0B00:00: setting system clock to 2026-10-19T20:04:19 UTC (1792440259)1731test # [ 6.507679] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1732second # [ 6.534052] parport_pc 00:02: reported by Plug and Play ACPI1733second # [ 6.534132] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1734second # [ 6.547180] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input41735test # [ 6.488489] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1736test # [ 6.492798] systemd[1]: Finished Create SUID/SGID Wrappers.1737second # [ 6.493365] systemd[1]: Finished register-nix-paths.service.1738second # [ 6.495469] systemd-networkd[503]: /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.1739test # [ 6.504747] systemd[1]: Finished register-nix-paths.service.1740test # [ 6.505846] systemd[1]: Reached target System Initialization.1741test # [ 6.508648] systemd[1]: Started Discard unused filesystem blocks once a week.1742second # [ 6.500408] systemd-networkd[503]: /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.1743test # [ 6.509858] systemd[1]: Started Daily Cleanup of Temporary Directories.1744second # [ 6.504219] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1745test # [ 6.512227] systemd[1]: Reached target Timer Units.1746second # [ 6.506757] systemd-networkd[503]: lo: Link UP1747test # [ 6.513620] systemd[1]: Listening on D-Bus System Message Bus Socket.1748second # [ 6.508151] systemd-networkd[503]: lo: Gained carrier1749test # [ 6.515836] systemd[1]: Listening on Nix Daemon Socket.1750second # [ 6.510208] systemd[1]: Finished Create SUID/SGID Wrappers.1751second # [ 6.511516] systemd[1]: Reached target System Initialization.1752test # [ 6.517295] systemd-networkd[521]: /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.1753test # [ 6.509524] systemd-journald[336]: Time jumped backwards, rotating.1754test # [ 6.569784] Console: switching to colour dummy device 80x251755second # [ 6.514794] systemd[1]: Started Discard unused filesystem blocks once a week.1756test # [ 6.611424] cfg80211: Loading compiled-in X.509 certificates for regulatory database1757second # [ 6.516149] systemd[1]: Started Daily Cleanup of Temporary Directories.1758second # [ 6.518167] systemd[1]: Reached target Timer Units.1759test # [ 6.523245] systemd-networkd[521]: /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.1760second # [ 6.520126] systemd[1]: Listening on D-Bus System Message Bus Socket.1761test # [ 6.527116] systemd-networkd[521]: lo: Link UP1762second # [ 6.521275] systemd[1]: Listening on Nix Daemon Socket.1763test # [ 6.527948] systemd-networkd[521]: lo: Gained carrier1764test # [ 6.531076] systemd[1]: Listening on Authorization Manager Agent Helper.1765test # [ 6.532213] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1766second # [ 6.525386] systemd-networkd[503]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1767test # [ 6.533596] systemd[1]: Reached target Socket Units.1768second # [ 6.528170] systemd-networkd[503]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1769second # [ 6.530312] systemd[1]: Listening on Authorization Manager Agent Helper.1770test # [ 6.537496] systemd[1]: Starting D-Bus System Message Bus...1771test # [ 6.538817] systemd[1]: Started Network Management.1772second # [ 6.533155] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1773second # [ 6.534600] systemd[1]: Reached target Socket Units.1774second # [ 6.537146] systemd-networkd[503]: eth1: Link UP1775test # [ 6.543703] systemd-networkd[521]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1776second # [ 6.541753] systemd[1]: Starting D-Bus System Message Bus...1777test # [ 6.548149] systemd-networkd[521]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1778second # [ 6.543131] systemd[1]: Started Network Management.1779test # [ 6.549717] systemd-networkd[521]: eth1: Link UP1780second # [ 6.544032] systemd-networkd[503]: eth1: Gained carrier1781test # [ 6.553147] systemd-networkd[521]: eth1: Gained carrier1782second # [ 6.545943] systemd-networkd[503]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1783test # [ 6.555243] systemd-networkd[521]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1784test # [ 6.557676] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1785second # [ 6.553118] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1786second # [ 6.575631] Console: switching to colour dummy device 80x251787second # [ 6.591749] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1788second # [ 6.594593] cfg80211: Loading compiled-in X.509 certificates for regulatory database1789test # [ 6.619804] parport_pc 00:02: reported by Plug and Play ACPI1790test # [ 6.619886] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1791second # [ 6.593066] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1792test # [ 6.628597] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1793test # [ 6.601108] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1794second # [ 6.635754] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1795second # [ 6.635838] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1796test # [ 6.646925] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1797test # [ 6.647007] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1798second # [ 6.736066] NET: Registered PF_PACKET protocol family1799second # [ 6.773854] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1800second # [ 6.773959] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1801test # [ 6.743811] NET: Registered PF_PACKET protocol family1802test # [ 6.785695] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1803test # [ 6.785801] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1804test # [ 6.646903] dbus-broker-launch[570]: Looking up NSS user entry for 'systemd-timesync'...1805second # [ 6.804832] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61806second # [ 6.805591] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51807second # [ 6.643376] dbus-broker-launch[549]: Looking up NSS user entry for 'systemd-timesync'...1808test # [ 6.652662] dbus-broker-launch[570]: NSS returned no entry for 'systemd-timesync'1809test # [ 6.653929] dbus-broker-launch[570]: Invalid user-name in /nix/store/z44927shx8yi5dn7hyc7dawgcrfmiqpj-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1810second # [ 6.649115] dbus-broker-launch[549]: NSS returned no entry for 'systemd-timesync'1811second # [ 6.650353] dbus-broker-launch[549]: Invalid user-name in /nix/store/z44927shx8yi5dn7hyc7dawgcrfmiqpj-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1812test # [ 6.810617] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61813test # [ 6.810914] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51814second # [ 6.832102] [drm] Found bochs VGA, ID 0xb0c5.1815second # [ 6.832645] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1816test # [ 6.846444] [drm] Found bochs VGA, ID 0xb0c5.1817test # [ 6.846446] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1818test # [ 6.850094] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1819test # [ 6.850922] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01820second # [ 6.847176] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1821second # [ 6.848003] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01822test # [ 6.691510] systemd[1]: Starting Virtual Console Setup...1823second # [ 6.689033] systemd[1]: Starting Virtual Console Setup...1824test # [ 6.698142] dbus-broker-launch[570]: 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'1825second # [ 6.691745] dbus-broker-launch[549]: 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'1826test # [ 6.701834] dbus-broker-launch[570]: 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'1827second # [ 6.696365] dbus-broker-launch[549]: 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'1828test # [ 6.705605] dbus-broker-launch[570]: 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'1829second # [ 6.702361] dbus-broker-launch[549]: 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'1830test # [ 6.709323] dbus-broker-launch[570]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/d527hwwggm2wz18p4v8cr39amcpx3g4c-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1831test # [ 6.713184] systemd[1]: Started D-Bus System Message Bus.1832second # [ 6.706173] dbus-broker-launch[549]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/d527hwwggm2wz18p4v8cr39amcpx3g4c-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1833second # [ 6.711078] systemd[1]: Started D-Bus System Message Bus.1834test # [ 6.718245] systemd[1]: Reached target Basic System.1835second # [ 6.712283] systemd[1]: Reached target Basic System.1836test # [ 6.724174] systemd[1]: Starting Network Manager Script Dispatcher Service...1837second # [ 6.718793] systemd[1]: Starting Network Manager Script Dispatcher Service...1838test # [ 6.730315] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1839second # [ 6.728076] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1840test # [ 6.736865] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1841second # [ 6.737943] systemd[1]: Starting Network Manager...1842test # [ 6.745147] dbus-broker-launch[570]: Ready1843second # [ 6.745953] dbus-broker-launch[549]: Ready1844test # [ 6.753405] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1845second # [ 6.749826] systemd[1]: Started backdoor.service.1846second # [ 6.754762] systemd[1]: Starting Import lastlog data into lastlog2 database...1847test # [ 6.774932] systemd[1]: Starting Network Manager...1848second # [ 6.776593] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1849test # [ 6.791846] systemd[1]: Started backdoor.service.1850second # [ 6.790167] systemd[1]: Starting Post-Boot Actions...1851second # [ 6.800039] 5y3ssc77g9a48j0jag4pj6wrwmh8mnj4-wifi-secrets[560]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1852second # [ 6.809457] systemd[1]: Started Reset console on configuration changes.1853test # [ 6.828623] systemd[1]: Starting Import lastlog data into lastlog2 database...1854test # [ 6.836842] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1855test # [ 6.838276] 5y3ssc77g9a48j0jag4pj6wrwmh8mnj4-wifi-secrets[581]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1856second # [ 6.840757] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1857test # [ 6.851253] systemd[1]: Starting Post-Boot Actions...1858test # [ 6.863585] 2fzr21av002hkddm9grsnsbb2wa3c1bn-wifi-secrets[582]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1859second # [ 6.858768] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1860test # [ 6.869917] bjn7ymirp1h8ai1aki9b9jfc6bwb1c5s-wifi-secrets[583]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1861test # [ 6.887637] systemd[1]: Started Reset console on configuration changes.1862second # connecting to host...1863test # connecting to host...1864second # [ 6.912323] systemd[1]: Finished Post-Boot Actions.1865test # [ 6.920462] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1866second # [ 6.923756] nsncd[564]: Oct 19 20:04:21.073 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1867second # [ 6.928695] systemd[1]: Started Name Service Cache Daemon (nsncd).1868test # [ 6.941123] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1869test # [ 6.942312] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1870second # [ 6.940159] systemd[1]: Finished Import lastlog data into lastlog2 database.1871test: Guest shell says: b'Spawning backdoor root shell...\n'1872test # [ 6.952383] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1873test # [ 6.953504] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1874test: connected to guest root shell1875test: (connecting took 7.59 seconds)1876test: (finished: waiting for the VM to finish booting, in 7.59 seconds)1877test # [ 6.962286] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1878second # [ 6.951092] systemd[1]: Reached target Host and Network Name Lookups.1879second # [ 6.952238] systemd[1]: Reached target User and Group Name Lookups.1880second # [ 6.955070] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1881second # [ 6.962869] systemd[1]: Starting User Login Management...1882second # [ 6.965444] systemd[1]: Started Network Manager Script Dispatcher Service.1883test # [ 6.987384] systemd[1]: Finished Post-Boot Actions.1884test # [ 7.006461] nsncd[591]: Oct 19 20:04:20.165 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1885test # [ 7.025743] systemd[1]: Started Name Service Cache Daemon (nsncd).1886second # [ 7.173454] ppdev: user-space parallel port driver1887second # [ 7.204568] Console: switching to colour frame buffer device 160x501888second # [ 7.206378] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1889second # [ 7.020237] systemd[1]: Starting Hostname Service...1890second # [ 7.044880] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1891second # [ 7.046889] systemd[1]: Stopped Virtual Console Setup.1892second # [ 7.052805] systemd[1]: Starting Virtual Console Setup...1893test # [ 7.062631] systemd[1]: Finished Import lastlog data into lastlog2 database.1894second # [ 7.062257] systemd-logind[598]: Watching system buttons on /dev/input/event2 (Power Button)1895second # [ 7.063818] systemd-logind[598]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1896test # [ 7.071572] systemd[1]: Started Network Manager Script Dispatcher Service.1897second # [ 7.067470] systemd-logind[598]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1898test # [ 7.242824] Console: switching to colour frame buffer device 160x501899second # [ 7.071168] systemd-logind[598]: New seat seat0.1900test # [ 7.078458] systemd[1]: Reached target Host and Network Name Lookups.1901test # [ 7.079606] systemd[1]: Reached target User and Group Name Lookups.1902test # [ 7.080665] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1903second # [ 7.075193] systemd[1]: Started User Login Management.1904test # [ 7.086813] systemd[1]: Starting User Login Management...1905second # [ 7.083144] systemd[1]: Starting linger-users.service...1906test # [ 7.263317] ppdev: user-space parallel port driver1907test # [ 7.287316] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1908test # [ 7.097936] systemd[1]: Starting Hostname Service...1909second # [ 7.289340] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1910test # [ 7.131694] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1911test # [ 7.134365] systemd[1]: Stopped Virtual Console Setup.1912second # [ 7.133369] systemd[1]: linger-users.service: Deactivated successfully.1913test # [ 7.144569] systemd[1]: Starting Virtual Console Setup...1914second # [ 7.139082] systemd[1]: Finished linger-users.service.1915second # [ 7.155072] systemd[1]: Started Hostname Service.1916second # [ 7.330755] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1917test # [ 7.346075] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1918second # [ 7.178079] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1919second # [ 7.179637] systemd[1]: Stopped Virtual Console Setup.1920test # [ 7.193104] systemd-logind[638]: New seat seat0.1921test # [ 7.194909] systemd-logind[638]: Watching system buttons on /dev/input/event2 (Power Button)1922second # [ 7.189899] systemd[1]: Starting Virtual Console Setup...1923test # [ 7.200383] systemd-logind[638]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1924test # [ 7.201817] systemd-logind[638]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1925test # [ 7.203305] systemd[1]: Started User Login Management.1926test # [ 7.209601] systemd[1]: Starting linger-users.service...1927test # [ 7.388984] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1928test # [ 7.225263] systemd[1]: Started Hostname Service.1929second # [ 7.219793] systemd-networkd[503]: eth0: Link UP1930second # [ 7.220686] systemd-networkd[503]: eth0: Gained carrier1931test # [ 7.236728] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1932test # [ 7.243667] systemd[1]: Stopped Virtual Console Setup.1933test # [ 7.249781] systemd[1]: Starting Virtual Console Setup...1934second # [ 7.245380] systemd[1]: Started Network Manager.1935second # [ 7.247627] systemd[1]: Reached target Network.1936test # [ 7.260400] systemd[1]: linger-users.service: Deactivated successfully.1937second # [ 7.253856] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1938test # [ 7.263248] systemd[1]: Finished linger-users.service.1939second # [ 7.259210] systemd[1]: Starting Permit User Sessions...1940test # [ 7.296625] systemd-networkd[521]: eth0: Link UP1941test # [ 7.297509] systemd-networkd[521]: eth0: Gained carrier1942test # [ 7.319369] systemd[1]: Started Network Manager.1943second # [ 7.314393] systemd[1]: Finished Permit User Sessions.1944test # [ 7.324703] systemd[1]: Reached target Network.1945test # [ 7.368794] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1946second # [ 7.363540] systemd[1]: Started Getty on tty1.1947second # [ 7.367655] systemd[1]: Reached target Login Prompts.1948test # [ 7.374982] systemd[1]: Starting Permit User Sessions...1949second # [ 7.556529] kvm_amd: TSC scaling supported1950second # [ 7.557012] kvm_amd: Nested Virtualization enabled1951second # [ 7.562683] kvm_amd: Nested Paging enabled1952second # [ 7.563155] kvm_amd: LBR virtualization supported1953second # [ 7.566895] kvm_amd: Virtual GIF supported1954second # [ 7.567377] kvm_amd: Virtual NMI enabled1955test # [ 7.586595] kvm_amd: TSC scaling supported1956second # [ 7.411508] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1957test # [ 7.587096] kvm_amd: Nested Virtualization enabled1958test # [ 7.588032] kvm_amd: Nested Paging enabled1959test # [ 7.588824] kvm_amd: LBR virtualization supported1960test # [ 7.589692] kvm_amd: Virtual GIF supported1961test # [ 7.590175] kvm_amd: Virtual NMI enabled1962second # [ 7.414821] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1963test # [ 7.431962] systemd[1]: Finished Permit User Sessions.1964second # [ 7.425722] systemd[1]: Reached target Multi-User System.1965test # [ 7.448619] systemd[1]: Started Getty on tty1.1966second # [ 7.612777] EDAC MC: Ver: 3.0.01967test # [ 7.452569] systemd[1]: Reached target Login Prompts.1968second # [ 7.473153] systemd-vconsole-setup[619]: Configuration of first virtual console was skipped, ignoring remaining ones.1969test # [ 7.649695] EDAC MC: Ver: 3.0.01970second # [ 7.476771] systemd[1]: Finished Virtual Console Setup.1971second # [ 7.478725] systemd[1]: Startup finished in 762ms (kernel) + 3.311s (initrd) + 3.404s (userspace) = 7.478s.1972test # [ 7.483973] NetworkManager[585]: <warn> [1792440260.6434] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1973test # [ 7.507619] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1974second # [ 7.502111] systemd-resolved[345]: eth0: Bus client set default route setting: yes1975test # [ 7.510474] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1976second # [ 7.506213] systemd-resolved[345]: eth0: Bus client set DNS server list to: 10.0.2.31977test # [ 7.513844] systemd[1]: Reached target Multi-User System.1978test # [ 7.529383] systemd-resolved[365]: eth0: Bus client set default route setting: yes1979test # [ 7.532416] systemd-resolved[365]: eth0: Bus client set DNS server list to: 10.0.2.31980test # [ 7.569331] systemd-vconsole-setup[654]: Configuration of first virtual console was skipped, ignoring remaining ones.1981test # [ 7.575079] systemd[1]: Finished Virtual Console Setup.1982test # [ 7.581187] systemd[1]: Startup finished in 767ms (kernel) + 3.312s (initrd) + 3.499s (userspace) = 7.580s.1983test # [ 7.948431] systemd-networkd[521]: eth1: Gained IPv6LL1984test: (finished: waiting for unit NetworkManager.service, in 8.71 seconds)1985test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1986test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.01 seconds)1987test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets1988test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets, in 0.01 seconds)1989test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection1990test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.01 seconds)1991(finished: run the VM test script, in 8.82 seconds)1992test script finished in 8.83s1993cleanup1994kill QemuMachine (pid 47)1995second # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/lb41b0anx1f98y9y5s9mdv97gjgsq740-python3-3.14.7/bin/python3.14)1996second # [2026-10-19T20:04:22Z INFO virtiofsd] Client disconnected, shutting down1997second # [2026-10-19T20:04:22Z INFO virtiofsd] Client disconnected, shutting down1998second # [2026-10-19T20:04:22Z INFO virtiofsd] Client disconnected, shutting down1999kill QemuMachine (pid 48)2000test # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/lb41b0anx1f98y9y5s9mdv97gjgsq740-python3-3.14.7/bin/python3.14)2001test # [2026-10-19T20:04:22Z INFO virtiofsd] Client disconnected, shutting down2002test # [2026-10-19T20:04:22Z INFO virtiofsd] Client disconnected, shutting down2003test # [2026-10-19T20:04:22Z INFO virtiofsd] Client disconnected, shutting down2004(finished: cleanup, in 0.21 seconds)