vm-test-run-wifi
checks.x86_64-linux.wifi
· build #269
· raw
1build-x86-01: building zblmn704w0qifjvh431s7avj9nw3zhy1-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.98BzCu8HCB', 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: 9ef198f3-b6f6-4756-89a7-ffe6a5fbf77f21test # 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-15T20:03:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)32test # [2026-10-15T20:03:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether33test # [2026-10-15T20:03:37Z INFO virtiofsd] Waiting for vhost-user socket connection...34test # [2026-10-15T20:03:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)35test # [2026-10-15T20:03:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether36test # [2026-10-15T20:03:37Z INFO virtiofsd] Waiting for vhost-user socket connection...37test # [2026-10-15T20:03:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)38test # [2026-10-15T20:03:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether39test # [2026-10-15T20:03:37Z INFO virtiofsd] Waiting for vhost-user socket connection...40test # [2026-10-15T20:03:37Z INFO virtiofsd] Client connected, servicing requests41test # [2026-10-15T20:03:37Z INFO virtiofsd] Client connected, servicing requests42test # [2026-10-15T20:03:37Z INFO virtiofsd] Client connected, servicing requests43second # Disk image does not exist, creating the virtualisation disk image...44second # Formatting '/build/vm-state-second/tmp.Pk0zeJuBcy', fmt=raw size=107374182445second # mke2fs 1.47.4 (6-Mar-2025)46second # Discarding device blocks: 0/262144 done47second # Creating filesystem with 262144 4k blocks and 65536 inodes48second # Filesystem UUID: c6576922-c10b-42fc-8019-c6b277bf925449second # Superblock backups stored on blocks:50second # 32768, 98304, 163840, 22937651second # 52second # Allocating group tables: 0/8 done53second # Writing inode tables: 0/8 done54second # Creating journal (8192 blocks): done55second # Writing superblocks and filesystem accounting information: 0/8 done56second # 57second # Virtualisation disk image created.58second # Starting virtiofs daemons...59second # [2026-10-15T20:03:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)60second # [2026-10-15T20:03:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether61second # [2026-10-15T20:03:37Z INFO virtiofsd] Waiting for vhost-user socket connection...62second # [2026-10-15T20:03:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)63second # [2026-10-15T20:03:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether64second # [2026-10-15T20:03:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)65second # [2026-10-15T20:03:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether66second # [2026-10-15T20:03:37Z INFO virtiofsd] Waiting for vhost-user socket connection...67second # [2026-10-15T20:03:37Z INFO virtiofsd] Waiting for vhost-user socket connection...68second # [2026-10-15T20:03:37Z INFO virtiofsd] Client connected, servicing requests69second # [2026-10-15T20:03:37Z INFO virtiofsd] Client connected, servicing requests70second # [2026-10-15T20:03:37Z INFO virtiofsd] Client connected, servicing requests71second: QEMU running (pid 47)72(finished: start all VMs, in 0.09 seconds)73test: waiting for unit NetworkManager.service74test: waiting for the VM to finish booting75second # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)76test # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)77second # 78second # 79test # 80test # 81second # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0082test # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0083second # Press Ctrl-B to configure iPXE (PCI 00:02.0)...84second # 85second # 86second # 87second # 88test # Press Ctrl-B to configure iPXE (PCI 00:02.0)...89test # 90test # 91test # 92test # 93second # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0094test # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0095second # Press Ctrl-B to configure iPXE (PCI 00:05.0)...96second # 97second # 98test # Press Ctrl-B to configure iPXE (PCI 00:05.0)...99test # 100test # 101second # Booting from ROM...102test # Booting from ROM...103second # 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 @1791725172104second # [ 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/0wyiskhdjxmy71yz8w5y37l1bajyffw1-nixos-system-second-test/init regInfo=/nix/.ro-store/5kd2mjq5v1sck7psmm8bh9186akqdzp4-closure-info/registration console=ttyS0,115200n8 console=tty0105second # [ 0.000000] BIOS-provided physical RAM map:106second # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable107second # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved108second # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved109second # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable110second # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved111second # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved112second # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved113second # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved114second # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved115second # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved116second # [ 0.000000] NX (Execute Disable) protection: active117second # [ 0.000000] APIC: Static calls initialized118second # [ 0.000000] SMBIOS 2.8 present.119second # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014120second # [ 0.000000] DMI: Memory slots populated: 1/1121second # [ 0.000000] Hypervisor detected: KVM122second # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000123second # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00124second # [ 0.000000] kvm-clock: using sched offset of 386414476 cycles125second # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns126second # [ 0.000005] tsc: Detected 4192.112 MHz processor127second # [ 0.000540] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000128test # 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 @1791725172129second # [ 0.000567] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs130second # [ 0.000570] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT131second # [ 0.002147] found SMP MP-table at [mem 0x000f5450-0x000f545f]132second # [ 0.002159] Using GB pages for direct mapping133second # [ 0.002256] RAMDISK: [mem 0x3e2e4000-0x3ffcffff]134second # [ 0.002260] ACPI: Early table checksum verification disabled135test # [ 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/2rsgqqpdal2qkyav895xirhgy1vpy2qp-nixos-system-test-test/init regInfo=/nix/.ro-store/hrblw3mdl3dlwx7dgqzdpv1l7aab5vnz-closure-info/registration console=ttyS0,115200n8 console=tty0136test # [ 0.000000] BIOS-provided physical RAM map:137second # [ 0.002263] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )138test # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable139second # [ 0.002266] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)140test # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved141second # [ 0.002270] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)142test # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved143second # [ 0.002275] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)144test # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable145second # [ 0.002276] ACPI: FACS 0x000000003FFE0000 000040146test # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved147second # [ 0.002278] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)148test # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved149test # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved150second # [ 0.002279] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)151test # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved152second # [ 0.002280] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)153test # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved154second # [ 0.002281] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)155test # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved156second # [ 0.002282] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]157test # [ 0.000000] NX (Execute Disable) protection: active158test # [ 0.000000] APIC: Static calls initialized159second # [ 0.002283] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]160test # [ 0.000000] SMBIOS 2.8 present.161second # [ 0.002283] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]162test # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014163second # [ 0.002284] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]164test # [ 0.000000] DMI: Memory slots populated: 1/1165test # [ 0.000000] Hypervisor detected: KVM166second # [ 0.002284] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]167test # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000168second # [ 0.002284] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]169test # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00170second # [ 0.002285] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]171test # [ 0.000001] kvm-clock: using sched offset of 417296113 cycles172second # [ 0.002620] No NUMA configuration found173second # [ 0.002621] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]174test # [ 0.000004] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns175test # [ 0.000007] tsc: Detected 4192.112 MHz processor176second # [ 0.002624] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]177second # [ 0.002692] Zone ranges:178test # [ 0.000544] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000179second # [ 0.002693] DMA [mem 0x0000000000001000-0x0000000000ffffff]180test # [ 0.000572] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs181second # [ 0.002694] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]182second # [ 0.002695] Normal empty183test # [ 0.000574] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT184second # [ 0.002695] Device empty185second # [ 0.002696] Movable zone start for each node186test # [ 0.002143] found SMP MP-table at [mem 0x000f5450-0x000f545f]187second # [ 0.002697] Early memory node ranges188test # [ 0.002157] Using GB pages for direct mapping189test # [ 0.002244] RAMDISK: [mem 0x3e2e3000-0x3ffcffff]190second # [ 0.002697] node 0: [mem 0x0000000000001000-0x000000000009efff]191test # [ 0.002249] ACPI: Early table checksum verification disabled192second # [ 0.002698] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]193test # [ 0.002252] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )194second # [ 0.002699] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]195test # [ 0.002255] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)196second # [ 0.002713] On node 0, zone DMA: 1 pages in unavailable ranges197second # [ 0.002899] On node 0, zone DMA: 97 pages in unavailable ranges198test # [ 0.002259] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)199second # [ 0.015774] On node 0, zone DMA32: 40 pages in unavailable ranges200second # [ 0.016554] ACPI: PM-Timer IO Port: 0x608201test # [ 0.002265] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)202test # [ 0.002266] ACPI: FACS 0x000000003FFE0000 000040203second # [ 0.016567] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])204second # [ 0.016591] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23205test # [ 0.002268] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)206second # [ 0.016593] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)207test # [ 0.002269] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)208second # [ 0.016595] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)209test # [ 0.002270] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)210second # [ 0.016596] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)211test # [ 0.002271] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)212second # [ 0.016597] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)213test # [ 0.002272] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]214second # [ 0.016598] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)215second # [ 0.016601] ACPI: Using ACPI (MADT) for SMP configuration information216test # [ 0.002273] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]217second # [ 0.016602] ACPI: HPET id: 0x8086a201 base: 0xfed00000218test # [ 0.002273] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]219second # [ 0.016606] TSC deadline timer available220second # [ 0.016628] CPU topo: Max. logical packages: 1221test # [ 0.002274] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]222second # [ 0.016628] CPU topo: Max. logical dies: 1223test # [ 0.002274] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]224second # [ 0.016629] CPU topo: Max. dies per package: 1225second # [ 0.016650] CPU topo: Max. threads per core: 1226test # [ 0.002274] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]227second # [ 0.016652] CPU topo: Num. cores per package: 1228test # [ 0.002275] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]229second # [ 0.016653] CPU topo: Num. threads per package: 1230test # [ 0.002606] No NUMA configuration found231second # [ 0.016654] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs232test # [ 0.002608] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]233second # [ 0.016668] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()234test # [ 0.002610] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]235test # [ 0.002679] Zone ranges:236second # [ 0.016704] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]237test # [ 0.002679] DMA [mem 0x0000000000001000-0x0000000000ffffff]238second # [ 0.016705] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]239test # [ 0.002680] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]240test # [ 0.002681] Normal empty241second # [ 0.016707] [mem 0x40000000-0xafffffff] available for PCI devices242test # [ 0.002681] Device empty243test # [ 0.002682] Movable zone start for each node244second # [ 0.016709] Booting paravirtualized kernel on KVM245test # [ 0.002682] Early memory node ranges246test # [ 0.002683] node 0: [mem 0x0000000000001000-0x000000000009efff]247second # [ 0.016714] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns248test # [ 0.002683] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]249second # [ 0.020322] setup_percpu: NR_CPUS:512 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1250test # [ 0.002684] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]251second # [ 0.022189] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152252test # [ 0.002699] On node 0, zone DMA: 1 pages in unavailable ranges253second # [ 0.022221] kvm-guest: PV spinlocks disabled, single CPU254test # [ 0.002884] On node 0, zone DMA: 97 pages in unavailable ranges255test # [ 0.015640] On node 0, zone DMA32: 40 pages in unavailable ranges256test # [ 0.016399] ACPI: PM-Timer IO Port: 0x608257test # [ 0.016408] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])258test # [ 0.016427] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23259second # [ 0.022223] 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/0wyiskhdjxmy71yz8w5y37l1bajyffw1-nixos-system-second-test/init regInfo=/nix/.ro-store/5kd2mjq5v1sck7psmm8bh9186akqdzp4-closure-info/registration console=ttyS0,115200n8 console=tty0260test # [ 0.016429] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)261test # [ 0.016430] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)262second # [ 0.022289] Unknown kernel command line parameters "regInfo=/nix/.ro-store/5kd2mjq5v1sck7psmm8bh9186akqdzp4-closure-info/registration", will be passed to user space.263test # [ 0.016431] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)264second # [ 0.022480] random: crng init done265test # [ 0.016432] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)266second # [ 0.022480] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes267test # [ 0.016433] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)268second # [ 0.022496] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)269test # [ 0.016434] ACPI: Using ACPI (MADT) for SMP configuration information270test # [ 0.016435] ACPI: HPET id: 0x8086a201 base: 0xfed00000271second # [ 0.022885] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)272test # [ 0.016437] TSC deadline timer available273second # [ 0.022908] Fallback order for Node 0: 0274test # [ 0.016441] CPU topo: Max. logical packages: 1275second # [ 0.022910] Built 1 zonelists, mobility grouping on. Total pages: 262006276test # [ 0.016441] CPU topo: Max. logical dies: 1277second # [ 0.022911] Policy zone: DMA32278test # [ 0.016442] CPU topo: Max. dies per package: 1279second # [ 0.024678] mem auto-init: stack:all(zero), heap alloc:on, heap free:off280test # [ 0.016444] CPU topo: Max. threads per core: 1281test # [ 0.016445] CPU topo: Num. cores per package: 1282second # [ 0.026923] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1283test # [ 0.016445] CPU topo: Num. threads per package: 1284second # [ 0.028534] allocated 2097152 bytes of page_ext285test # [ 0.016445] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs286second # [ 0.037530] ftrace: allocating 48842 entries in 192 pages287second # [ 0.037531] ftrace: allocated 192 pages with 2 groups288test # [ 0.016457] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()289second # [ 0.038189] Dynamic Preempt: lazy290second # [ 0.038320] rcu: Preemptible hierarchical RCU implementation.291test # [ 0.016481] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]292second # [ 0.038320] rcu: RCU event tracing is enabled.293test # [ 0.016482] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]294second # [ 0.038321] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.295test # [ 0.016483] [mem 0x40000000-0xafffffff] available for PCI devices296second # [ 0.038322] Trampoline variant of Tasks RCU enabled.297test # [ 0.016484] Booting paravirtualized kernel on KVM298second # [ 0.038322] Rude variant of Tasks RCU enabled.299second # [ 0.038322] Tracing variant of Tasks RCU enabled.300test # [ 0.016487] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns301second # [ 0.038323] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.302test # [ 0.019765] setup_percpu: NR_CPUS:512 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1303second # [ 0.038323] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1304test # [ 0.021573] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152305test # [ 0.021602] kvm-guest: PV spinlocks disabled, single CPU306second # [ 0.038340] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.307second # [ 0.038341] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.308second # [ 0.038341] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.309second # [ 0.041422] NR_IRQS: 33024, nr_irqs: 256, preallocated irqs: 16310test # [ 0.021603] 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/2rsgqqpdal2qkyav895xirhgy1vpy2qp-nixos-system-test-test/init regInfo=/nix/.ro-store/hrblw3mdl3dlwx7dgqzdpv1l7aab5vnz-closure-info/registration console=ttyS0,115200n8 console=tty0311second # [ 0.041671] rcu: srcu_init: Setting srcu_struct sizes based on contention.312second # [ 0.041676] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns313test # [ 0.021670] Unknown kernel command line parameters "regInfo=/nix/.ro-store/hrblw3mdl3dlwx7dgqzdpv1l7aab5vnz-closure-info/registration", will be passed to user space.314test # [ 0.021860] random: crng init done315second # [ 0.041832] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)316second # [ 0.046848] Console: colour VGA+ 80x25317test # [ 0.021860] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes318second # [ 0.046851] printk: legacy console [tty0] enabled319test # [ 0.021875] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)320second # [ 0.076141] printk: legacy console [ttyS0] enabled321second # [ 0.206069] ACPI: Core revision 20250807322test # [ 0.022269] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)323test # [ 0.022292] Fallback order for Node 0: 0324test # [ 0.022295] Built 1 zonelists, mobility grouping on. Total pages: 262006325second # [ 0.207119] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns326test # [ 0.022295] Policy zone: DMA32327second # [ 0.209008] APIC: Switch to symmetric I/O mode setup328test # [ 0.024061] mem auto-init: stack:all(zero), heap alloc:on, heap free:off329second # [ 0.210165] x2apic enabled330test # [ 0.026191] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1331second # [ 0.210955] APIC: Switched APIC routing to: physical x2apic332test # [ 0.027791] allocated 2097152 bytes of page_ext333test # [ 0.036590] ftrace: allocating 48842 entries in 192 pages334test # [ 0.036591] ftrace: allocated 192 pages with 2 groups335second # [ 0.212892] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1336test # [ 0.037227] Dynamic Preempt: lazy337test # [ 0.037353] rcu: Preemptible hierarchical RCU implementation.338test # [ 0.037353] rcu: RCU event tracing is enabled.339second # [ 0.214090] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3c6d4505823, max_idle_ns: 440795228884 ns340test # [ 0.037354] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.341second # [ 0.216096] Calibrating delay loop (skipped) preset value.. 8384.22 BogoMIPS (lpj=4192112)342test # [ 0.037355] Trampoline variant of Tasks RCU enabled.343test # [ 0.037355] Rude variant of Tasks RCU enabled.344second # [ 0.217180] x86/cpu: User Mode Instruction Prevention (UMIP) activated345test # [ 0.037355] Tracing variant of Tasks RCU enabled.346test # [ 0.037356] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.347second # [ 0.219443] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127348test # [ 0.037357] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1349second # [ 0.220096] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0350test # [ 0.037369] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.351second # [ 0.221098] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto352test # [ 0.037370] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.353second # [ 0.223095] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl354test # [ 0.037370] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.355second # [ 0.225095] Spectre V2 : Mitigation: Enhanced / Automatic IBRS356test # [ 0.040450] NR_IRQS: 33024, nr_irqs: 256, preallocated irqs: 16357test # [ 0.040680] rcu: srcu_init: Setting srcu_struct sizes based on contention.358second # [ 0.226095] Speculative Return Stack Overflow: Mitigation: Safe RET359test # [ 0.040684] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns360second # [ 0.227095] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization361test # [ 0.040833] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)362test # [ 0.045847] Console: colour VGA+ 80x25363test # [ 0.045850] printk: legacy console [tty0] enabled364test # [ 0.075249] printk: legacy console [ttyS0] enabled365test # [ 0.204142] ACPI: Core revision 20250807366second # [ 0.229100] Spectre V2 : Enabling IBPB for BPF367second # [ 0.231096] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier368test # [ 0.205159] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns369second # [ 0.232096] active return thunk: srso_alias_return_thunk370test # [ 0.207022] APIC: Switch to symmetric I/O mode setup371test # [ 0.208173] x2apic enabled372second # [ 0.233115] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'373test # [ 0.208946] APIC: Switched APIC routing to: physical x2apic374second # [ 0.235095] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'375second # [ 0.236095] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'376test # [ 0.210868] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1377second # [ 0.237095] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'378test # [ 0.212084] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3c6d4505823, max_idle_ns: 440795228884 ns379second # [ 0.238095] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'380second # [ 0.240095] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'381test # [ 0.214108] Calibrating delay loop (skipped) preset value.. 8384.22 BogoMIPS (lpj=4192112)382second # [ 0.241095] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'383test # [ 0.215190] x86/cpu: User Mode Instruction Prevention (UMIP) activated384test # [ 0.217210] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127385second # [ 0.242095] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'386test # [ 0.218108] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0387second # [ 0.244095] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'388second # [ 0.245095] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256389test # [ 0.219110] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto390second # [ 0.246095] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64391test # [ 0.220108] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl392second # [ 0.247095] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512393test # [ 0.222108] Spectre V2 : Mitigation: Enhanced / Automatic IBRS394second # [ 0.248095] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024395test # [ 0.223108] Speculative Return Stack Overflow: Mitigation: Safe RET396second # [ 0.249095] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8397second # [ 0.251095] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16398test # [ 0.224108] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization399second # [ 0.252095] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24400second # [ 0.253095] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format.401test # [ 0.226113] Spectre V2 : Enabling IBPB for BPF402test # [ 0.227810] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier403test # [ 0.229108] active return thunk: srso_alias_return_thunk404test # [ 0.230127] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'405test # [ 0.232108] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'406test # [ 0.233108] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'407test # [ 0.234108] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'408test # [ 0.235108] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'409test # [ 0.237108] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'410test # [ 0.238108] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'411test # [ 0.239108] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'412test # [ 0.240108] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'413test # [ 0.241108] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256414test # [ 0.242108] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64415test # [ 0.243108] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512416test # [ 0.244108] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024417test # [ 0.246108] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8418test # [ 0.247108] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16419second # [ 0.278624] Freeing SMP alternatives memory: 48K420test # [ 0.248108] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24421second # [ 0.279096] pid_max: default: 32768 minimum: 301422test # [ 0.249108] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format.423second # [ 0.280137] LSM: initializing lsm=capability,landlock,yama,bpf,ima424second # [ 0.281184] landlock: Up and running.425second # [ 0.282095] Yama: becoming mindful.426second # [ 0.283267] LSM support for eBPF active427second # [ 0.284176] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)428second # [ 0.285112] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)429second # [ 0.287755] smpboot: CPU0: AMD Ryzen 9 7950X3D 16-Core Processor (family: 0x19, model: 0x61, stepping: 0x2)430second # [ 0.288478] Performance Events: Fam17h+ core perfctr, AMD PMU driver.431second # [ 0.289098] ... version: 2432second # [ 0.289957] ... bit width: 48433second # [ 0.290774] ... generic counters: 6434second # [ 0.291105] ... generic bitmap: 000000000000003f435second # [ 0.292096] ... fixed-purpose counters: 0436second # [ 0.292945] ... fixed-purpose bitmap: 0000000000000000437second # [ 0.293959] ... value mask: 0000ffffffffffff438second # [ 0.294965] ... max period: 00007fffffffffff439second # [ 0.295967] ... global_ctrl mask: 000000000000003f440second # [ 0.297060] signal: max sigframe size: 3376441second # [ 0.297805] rcu: Hierarchical SRCU implementation.442second # [ 0.298100] rcu: Max phase no-delay instances is 400.443test # [ 0.275579] Freeing SMP alternatives memory: 48K444second # [ 0.302047] smp: Bringing up secondary CPUs ...445test # [ 0.276109] pid_max: default: 32768 minimum: 301446second # [ 0.302821] smp: Brought up 1 node, 1 CPU447test # [ 0.277147] LSM: initializing lsm=capability,landlock,yama,bpf,ima448second # [ 0.303097] smpboot: Total of 1 processors activated (8384.22 BogoMIPS)449test # [ 0.278193] landlock: Up and running.450test # [ 0.279108] Yama: becoming mindful.451test # [ 0.280278] LSM support for eBPF active452second # [ 0.304221] Memory: 942472K/1048024K available (18132K kernel code, 2734K rwdata, 13652K rodata, 3660K init, 3620K bss, 98116K reserved, 0K cma-reserved)453second # [ 0.305238] devtmpfs: initialized454test # [ 0.281419] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)455second # [ 0.306140] x86/mm: Memory block size: 128MB456test # [ 0.283128] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)457second # [ 0.307704] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)458test # [ 0.285361] smpboot: CPU0: AMD Ryzen 9 7950X3D 16-Core Processor (family: 0x19, model: 0x61, stepping: 0x2)459second # [ 0.308117] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).460second # [ 0.309166] pinctrl core: initialized pinctrl subsystem461test # [ 0.286496] Performance Events: Fam17h+ core perfctr, AMD PMU driver.462test # [ 0.287115] ... version: 2463second # [ 0.310339] PM: RTC time: 20:03:37, date: 2026-10-15464test # [ 0.287991] ... bit width: 48465test # [ 0.288809] ... generic counters: 6466test # [ 0.289109] ... generic bitmap: 000000000000003f467second # [ 0.313002] NET: Registered PF_NETLINK/PF_ROUTE protocol family468test # [ 0.290109] ... fixed-purpose counters: 0469test # [ 0.290980] ... fixed-purpose bitmap: 0000000000000000470second # [ 0.314280] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations471test # [ 0.291996] ... value mask: 0000ffffffffffff472second # [ 0.315114] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations473test # [ 0.292992] ... max period: 00007fffffffffff474second # [ 0.316210] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations475test # [ 0.294002] ... global_ctrl mask: 000000000000003f476second # [ 0.317103] audit: initializing netlink subsys (disabled)477test # [ 0.295064] signal: max sigframe size: 3376478test # [ 0.295820] rcu: Hierarchical SRCU implementation.479second # [ 0.318295] thermal_sys: Registered thermal governor 'fair_share'480test # [ 0.296112] rcu: Max phase no-delay instances is 400.481second # [ 0.318296] thermal_sys: Registered thermal governor 'bang_bang'482second # [ 0.319097] thermal_sys: Registered thermal governor 'step_wise'483second # [ 0.320099] audit: type=2000 audit(1792094617.669:1): state=initialized audit_enabled=0 res=1484test # [ 0.300057] smp: Bringing up secondary CPUs ...485second # [ 0.322098] thermal_sys: Registered thermal governor 'user_space'486test # [ 0.300848] smp: Brought up 1 node, 1 CPU487second # [ 0.322100] thermal_sys: Registered thermal governor 'power_allocator'488test # [ 0.301110] smpboot: Total of 1 processors activated (8384.22 BogoMIPS)489second # [ 0.323107] cpuidle: using governor menu490second # [ 0.325696] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5491test # [ 0.302240] Memory: 942464K/1048024K available (18132K kernel code, 2734K rwdata, 13652K rodata, 3660K init, 3620K bss, 98120K reserved, 0K cma-reserved)492test # [ 0.303260] devtmpfs: initialized493second # [ 0.326289] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]494test # [ 0.304188] x86/mm: Memory block size: 128MB495second # [ 0.327100] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry496second # [ 0.328104] PCI: Using configuration type 1 for base access497test # [ 0.305772] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)498test # [ 0.306130] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).499second # [ 0.329234] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.500test # [ 0.307168] pinctrl core: initialized pinctrl subsystem501test # [ 0.308335] PM: RTC time: 20:03:37, date: 2026-10-15502test # [ 0.311069] NET: Registered PF_NETLINK/PF_ROUTE protocol family503second # [ 0.334268] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages504second # [ 0.335097] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page505test # [ 0.312329] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations506test # [ 0.313124] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations507test # [ 0.314228] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations508test # [ 0.315116] audit: initializing netlink subsys (disabled)509second # [ 0.340096] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages510test # [ 0.316284] thermal_sys: Registered thermal governor 'fair_share'511second # [ 0.341096] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page512test # [ 0.316286] thermal_sys: Registered thermal governor 'bang_bang'513test # [ 0.317109] thermal_sys: Registered thermal governor 'step_wise'514test # [ 0.318112] audit: type=2000 audit(1792094617.697:1): state=initialized audit_enabled=0 res=1515test # [ 0.320111] thermal_sys: Registered thermal governor 'user_space'516test # [ 0.320112] thermal_sys: Registered thermal governor 'power_allocator'517test # [ 0.321121] cpuidle: using governor menu518test # [ 0.323735] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5519second # [ 0.350400] ACPI: Added _OSI(Module Device)520second # [ 0.351097] ACPI: Added _OSI(Processor Device)521test # [ 0.324319] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]522second # [ 0.351989] ACPI: Added _OSI(Processor Aggregator Device)523test # [ 0.325112] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry524test # [ 0.326116] PCI: Using configuration type 1 for base access525test # [ 0.327241] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.526second # [ 0.356885] ACPI: 1 ACPI AML tables successfully acquired and loaded527test # [ 0.332269] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages528second # [ 0.362435] ACPI: Interpreter enabled529test # [ 0.333109] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page530second # [ 0.363108] ACPI: PM: (supports S0 S3 S4 S5)531second # [ 0.363961] ACPI: Using IOAPIC for interrupt routing532second # [ 0.364909] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug533second # [ 0.366096] PCI: Using E820 reservations for host bridge windows534test # [ 0.338109] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages535second # [ 0.367203] ACPI: Enabled 2 GPEs in block 00 to 3F536test # [ 0.339109] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page537second # [ 0.371163] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])538second # [ 0.372101] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]539test # [ 0.344816] ACPI: Added _OSI(Module Device)540second # [ 0.373153] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]541second # [ 0.374188] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]542test # [ 0.347109] ACPI: Added _OSI(Processor Device)543second # [ 0.375486] PCI host bridge to bus 0000:00544test # [ 0.348016] ACPI: Added _OSI(Processor Aggregator Device)545second # [ 0.376100] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]546second # [ 0.377097] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]547second # [ 0.378097] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]548second # [ 0.379097] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]549test # [ 0.353763] ACPI: 1 ACPI AML tables successfully acquired and loaded550second # [ 0.380097] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]551second # [ 0.381097] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]552second # [ 0.382097] pci_bus 0000:00: root bus resource [bus 00-ff]553test # [ 0.356868] ACPI: Interpreter enabled554test # [ 0.357122] ACPI: PM: (supports S0 S3 S4 S5)555second # [ 0.383176] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint556test # [ 0.357982] ACPI: Using IOAPIC for interrupt routing557second # [ 0.384710] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint558test # [ 0.358902] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug559test # [ 0.362476] PCI: Using E820 reservations for host bridge windows560second # [ 0.387070] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]561test # [ 0.363217] ACPI: Enabled 2 GPEs in block 00 to 3F562second # [ 0.388116] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]563second # [ 0.389133] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]564second # [ 0.390256] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]565test # [ 0.367241] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])566second # [ 0.391792] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint567test # [ 0.368114] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]568test # [ 0.369166] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]569second # [ 0.393107] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]570test # [ 0.370202] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]571second # [ 0.394107] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]572test # [ 0.371482] PCI host bridge to bus 0000:00573second # [ 0.395185] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]574test # [ 0.372114] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]575second # [ 0.396107] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]576test # [ 0.373110] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]577test # [ 0.374110] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]578second # [ 0.398115] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint579test # [ 0.375110] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]580test # [ 0.376110] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]581second # [ 0.400791] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]582test # [ 0.377110] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]583second # [ 0.401107] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]584test # [ 0.378110] pci_bus 0000:00: root bus resource [bus 00-ff]585second # [ 0.402134] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]586test # [ 0.379235] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint587second # [ 0.403936] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint588test # [ 0.380743] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint589second # [ 0.406107] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]590second # [ 0.407107] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]591second # [ 0.408134] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]592test # [ 0.383075] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]593test # [ 0.384128] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]594second # [ 0.409925] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint595test # [ 0.385146] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]596test # [ 0.386285] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]597second # [ 0.412107] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]598second # [ 0.413107] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]599test # [ 0.387825] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint600second # [ 0.414133] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]601second # [ 0.415107] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]602test # [ 0.390119] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]603second # [ 0.417036] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint604test # [ 0.391125] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]605test # [ 0.392146] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]606second # [ 0.418892] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]607test # [ 0.393165] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]608second # [ 0.420060] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]609test # [ 0.395128] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint610second # [ 0.421919] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint611second # [ 0.424043] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]612test # [ 0.397860] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]613second # [ 0.425134] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]614test # [ 0.398119] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]615test # [ 0.399146] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]616second # [ 0.426966] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint617test # [ 0.400930] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint618second # [ 0.428864] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]619second # [ 0.429134] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]620test # [ 0.403129] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]621second # [ 0.430945] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint622test # [ 0.404119] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]623test # [ 0.405146] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]624second # [ 0.432972] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]625second # [ 0.434055] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]626test # [ 0.406961] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint627second # [ 0.435914] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint628test # [ 0.409134] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]629test # [ 0.410119] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]630second # [ 0.438114] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]631second # [ 0.439107] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]632test # [ 0.411146] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]633second # [ 0.440134] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]634test # [ 0.412119] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]635second # [ 0.442047] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint636test # [ 0.413942] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint637second # [ 0.444034] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]638test # [ 0.416073] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]639second # [ 0.444932] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]640test # [ 0.417089] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]641second # [ 0.446061] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]642test # [ 0.418928] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint643second # [ 0.447957] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint644second # [ 0.449720] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]645test # [ 0.421103] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]646test # [ 0.422146] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]647test # [ 0.423988] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint648test # [ 0.426044] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]649test # [ 0.427079] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]650second # [ 0.450340] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint651test # [ 0.428941] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint652second # [ 0.453143] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]653test # [ 0.431038] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]654test # [ 0.432093] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]655second # [ 0.454342] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint656second # [ 0.456019] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]657test # [ 0.433953] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint658second # [ 0.457208] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint659test # [ 0.436135] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]660second # [ 0.459007] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]661test # [ 0.437119] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]662test # [ 0.438147] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]663second # [ 0.460364] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint664second # [ 0.461564] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO665test # [ 0.439960] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint666second # [ 0.462442] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint667test # [ 0.442132] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]668test # [ 0.443119] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]669second # [ 0.464075] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]670test # [ 0.444146] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]671second # [ 0.464924] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]672second # [ 0.466479] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint673test # [ 0.445980] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint674second # [ 0.468143] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]675test # [ 0.447819] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]676second # [ 0.473500] ACPI: PCI: Interrupt link LNKA configured for IRQ 10677second # [ 0.474209] ACPI: PCI: Interrupt link LNKB configured for IRQ 10678second # [ 0.475205] ACPI: PCI: Interrupt link LNKC configured for IRQ 11679second # [ 0.476207] ACPI: PCI: Interrupt link LNKD configured for IRQ 11680test # [ 0.448352] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint681second # [ 0.477207] ACPI: PCI: Interrupt link LNKE configured for IRQ 10682test # [ 0.450821] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]683second # [ 0.478205] ACPI: PCI: Interrupt link LNKF configured for IRQ 10684second # [ 0.479214] ACPI: PCI: Interrupt link LNKG configured for IRQ 11685test # [ 0.451358] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint686second # [ 0.480205] ACPI: PCI: Interrupt link LNKH configured for IRQ 11687test # [ 0.452802] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]688second # [ 0.481170] ACPI: PCI: Interrupt link GSIA configured for IRQ 16689test # [ 0.453382] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint690second # [ 0.482110] ACPI: PCI: Interrupt link GSIB configured for IRQ 17691second # [ 0.483112] ACPI: PCI: Interrupt link GSIC configured for IRQ 18692test # [ 0.454768] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]693second # [ 0.484109] ACPI: PCI: Interrupt link GSID configured for IRQ 19694second # [ 0.485112] ACPI: PCI: Interrupt link GSIE configured for IRQ 20695test # [ 0.456346] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint696second # [ 0.486113] ACPI: PCI: Interrupt link GSIF configured for IRQ 21697test # [ 0.457565] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO698second # [ 0.487109] ACPI: PCI: Interrupt link GSIG configured for IRQ 22699second # [ 0.488110] ACPI: PCI: Interrupt link GSIH configured for IRQ 23700test # [ 0.458433] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint701second # [ 0.489887] iommu: Default domain type: Translated702test # [ 0.460039] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]703second # [ 0.490104] iommu: DMA domain TLB invalidation policy: lazy mode704test # [ 0.460924] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]705second # [ 0.491313] ACPI: bus type USB registered706second # [ 0.492147] usbcore: registered new interface driver usbfs707test # [ 0.462732] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint708second # [ 0.493111] usbcore: registered new interface driver hub709second # [ 0.494103] usbcore: registered new device driver usb710test # [ 0.463796] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]711second # [ 0.495815] NetLabel: Initializing712second # [ 0.496101] NetLabel: domain hash size = 128713second # [ 0.496974] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO714second # [ 0.498030] NetLabel: unlabeled traffic allowed by default715second # [ 0.498972] PCI: Using ACPI for IRQ routing716test # [ 0.468494] ACPI: PCI: Interrupt link LNKA configured for IRQ 10717test # [ 0.469221] ACPI: PCI: Interrupt link LNKB configured for IRQ 10718test # [ 0.470227] ACPI: PCI: Interrupt link LNKC configured for IRQ 11719test # [ 0.471217] ACPI: PCI: Interrupt link LNKD configured for IRQ 11720test # [ 0.472220] ACPI: PCI: Interrupt link LNKE configured for IRQ 10721test # [ 0.473218] ACPI: PCI: Interrupt link LNKF configured for IRQ 10722test # [ 0.474220] ACPI: PCI: Interrupt link LNKG configured for IRQ 11723test # [ 0.475217] ACPI: PCI: Interrupt link LNKH configured for IRQ 11724test # [ 0.476155] ACPI: PCI: Interrupt link GSIA configured for IRQ 16725test # [ 0.477124] ACPI: PCI: Interrupt link GSIB configured for IRQ 17726test # [ 0.478124] ACPI: PCI: Interrupt link GSIC configured for IRQ 18727test # [ 0.479124] ACPI: PCI: Interrupt link GSID configured for IRQ 19728test # [ 0.480121] ACPI: PCI: Interrupt link GSIE configured for IRQ 20729test # [ 0.481121] ACPI: PCI: Interrupt link GSIF configured for IRQ 21730test # [ 0.482121] ACPI: PCI: Interrupt link GSIG configured for IRQ 22731test # [ 0.483125] ACPI: PCI: Interrupt link GSIH configured for IRQ 23732test # [ 0.484875] iommu: Default domain type: Translated733test # [ 0.485116] iommu: DMA domain TLB invalidation policy: lazy mode734test # [ 0.486335] ACPI: bus type USB registered735test # [ 0.487161] usbcore: registered new interface driver usbfs736test # [ 0.488123] usbcore: registered new interface driver hub737test # [ 0.489116] usbcore: registered new device driver usb738test # [ 0.490727] NetLabel: Initializing739test # [ 0.491113] NetLabel: domain hash size = 128740test # [ 0.491996] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO741test # [ 0.493022] NetLabel: unlabeled traffic allowed by default742test # [ 0.493998] PCI: Using ACPI for IRQ routing743second # [ 0.572452] pci 0000:00:01.0: vgaarb: setting as boot VGA device744second # [ 0.573093] pci 0000:00:01.0: vgaarb: bridge control possible745second # [ 0.573093] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none746second # [ 0.573098] vgaarb: loaded747second # [ 0.573862] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0748second # [ 0.574097] hpet0: 3 comparators, 64-bit 100.000000 MHz counter749second # [ 0.579159] clocksource: Switched to clocksource kvm-clock750second # [ 0.582102] VFS: Disk quotas dquot_6.6.0751second # [ 0.582934] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)752second # [ 0.584402] pnp: PnP ACPI init753second # [ 0.585224] ACPI: IRQ 4 override to edge(!), high(!)754second # [ 0.586322] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved755second # [ 0.588070] pnp: PnP ACPI: found 5 devices756second # [ 0.595077] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns757second # [ 0.596771] clocksource: Switched to clocksource acpi_pm758second # [ 0.597917] NET: Registered PF_INET protocol family759second # [ 0.599046] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)760test # [ 0.567542] pci 0000:00:01.0: vgaarb: setting as boot VGA device761test # [ 0.568106] pci 0000:00:01.0: vgaarb: bridge control possible762test # [ 0.568106] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none763test # [ 0.568111] vgaarb: loaded764test # [ 0.568883] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0765test # [ 0.569110] hpet0: 3 comparators, 64-bit 100.000000 MHz counter766test # [ 0.573179] clocksource: Switched to clocksource kvm-clock767second # [ 0.612038] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)768test # [ 0.576192] VFS: Disk quotas dquot_6.6.0769test # [ 0.577037] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)770second # [ 0.613763] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)771test # [ 0.578533] pnp: PnP ACPI init772second # [ 0.615306] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)773test # [ 0.579356] ACPI: IRQ 4 override to edge(!), high(!)774second # [ 0.616884] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)775test # [ 0.580463] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved776second # [ 0.618343] TCP: Hash tables configured (established 8192 bind 8192)777test # [ 0.582183] pnp: PnP ACPI: found 5 devices778second # [ 0.619670] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)779second # [ 0.621165] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)780second # [ 0.622486] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)781second # [ 0.623895] NET: Registered PF_UNIX/PF_LOCAL protocol family782second # [ 0.625056] NET: Registered PF_XDP protocol family783second # [ 0.626072] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]784test # [ 0.589260] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns785second # [ 0.627274] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]786test # [ 0.590954] clocksource: Switched to clocksource acpi_pm787second # [ 0.628517] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]788test # [ 0.592160] NET: Registered PF_INET protocol family789second # [ 0.629874] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]790test # [ 0.593315] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)791second # [ 0.631198] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]792second # [ 0.632549] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]793second # [ 0.634397] ACPI: \_SB_.GSIA: Enabled at IRQ 16794second # [ 0.636266] ACPI: \_SB_.GSIB: Enabled at IRQ 17795second # [ 0.638093] ACPI: \_SB_.GSIC: Enabled at IRQ 18796second # [ 0.639982] ACPI: \_SB_.GSID: Enabled at IRQ 19797second # [ 0.641829] PCI: CLS 0 bytes, default 64798second # [ 0.642815] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3c6d4505823, max_idle_ns: 440795228884 ns799test # [ 0.606556] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)800second # [ 0.644773] Trying to unpack rootfs image as initramfs...801test # [ 0.608175] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)802test # [ 0.609727] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)803test # [ 0.611263] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)804test # [ 0.612726] TCP: Hash tables configured (established 8192 bind 8192)805test # [ 0.614006] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)806test # [ 0.615515] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)807test # [ 0.616835] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)808test # [ 0.618233] NET: Registered PF_UNIX/PF_LOCAL protocol family809test # [ 0.619406] NET: Registered PF_XDP protocol family810test # [ 0.620412] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]811test # [ 0.621651] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]812test # [ 0.622858] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]813test # [ 0.624186] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]814test # [ 0.625505] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]815test # [ 0.626831] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]816test # [ 0.628701] ACPI: \_SB_.GSIA: Enabled at IRQ 16817test # [ 0.630711] ACPI: \_SB_.GSIB: Enabled at IRQ 17818test # [ 0.632644] ACPI: \_SB_.GSIC: Enabled at IRQ 18819test # [ 0.634513] ACPI: \_SB_.GSID: Enabled at IRQ 19820test # [ 0.636127] PCI: CLS 0 bytes, default 64821test # [ 0.637148] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3c6d4505823, max_idle_ns: 440795228884 ns822test # [ 0.639174] Trying to unpack rootfs image as initramfs...823second # [ 0.680738] Initialise system trusted keyrings824second # [ 0.682494] workingset: timestamp_bits=40 max_order=18 bucket_order=0825second # [ 0.698677] Key type asymmetric registered826second # [ 0.701457] Asymmetric key parser 'x509' registered827second # [ 0.702494] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)828second # [ 0.708590] io scheduler mq-deadline registered829second # [ 0.709537] io scheduler kyber registered830test # [ 0.674786] Initialise system trusted keyrings831second # [ 0.712847] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled832test # [ 0.676092] workingset: timestamp_bits=40 max_order=18 bucket_order=0833second # [ 0.714353] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A834second # [ 0.720253] Linux agpgart interface v0.103835second # [ 0.721167] ACPI: bus type drm_connector registered836second # [ 0.726019] usbcore: registered new interface driver usbserial_generic837second # [ 0.727303] usbserial: USB Serial support registered for generic838second # [ 0.728521] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.839test # [ 0.692705] Key type asymmetric registered840second # [ 0.728521] Please enable it if your BIOS has the CPPC option.841second # [ 0.731135] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled842test # [ 0.695460] Asymmetric key parser 'x509' registered843test # [ 0.696482] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)844second # [ 0.737539] drop_monitor: Initializing network drop monitor service845second # [ 0.738877] NET: Registered PF_INET6 protocol family846test # [ 0.702555] io scheduler mq-deadline registered847test # [ 0.703501] io scheduler kyber registered848second # [ 0.742834] Segment Routing with IPv6849second # [ 0.743649] In-situ OAM (IOAM) with IPv6850test # [ 0.706876] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled851test # [ 0.708375] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A852second # [ 0.746645] IPI shorthand broadcast: enabled853test # [ 0.714291] Linux agpgart interface v0.103854test # [ 0.715198] ACPI: bus type drm_connector registered855second # [ 0.751929] sched_clock: Marking stable (582020655, 169418190)->(823502928, -72064083)856test # [ 0.719809] usbcore: registered new interface driver usbserial_generic857second # [ 0.757681] registered taskstats version 1858test # [ 0.721127] usbserial: USB Serial support registered for generic859second # [ 0.758719] Loading compiled-in X.509 certificates860test # [ 0.724457] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.861test # [ 0.724457] Please enable it if your BIOS has the CPPC option.862test # [ 0.727068] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled863test # [ 0.731546] drop_monitor: Initializing network drop monitor service864test # [ 0.732873] NET: Registered PF_INET6 protocol family865second # [ 0.773452] Demotion targets for Node 0: null866test # [ 0.736812] Segment Routing with IPv6867second # [ 0.774472] Key type .fscrypt registered868second # [ 0.775287] Key type fscrypt-provisioning registered869second # [ 0.776375] ima: No TPM chip found, activating TPM-bypass!870test # [ 0.740470] In-situ OAM (IOAM) with IPv6871test # [ 0.741597] IPI shorthand broadcast: enabled872second # [ 0.781451] ima: Allocated hash algorithm: sha1873second # [ 0.782445] ima: No architecture policies found874test # [ 0.746889] sched_clock: Marking stable (578020183, 168422509)->(826463647, -80020955)875second # [ 0.785531] PM: Magic number: 14:786:93876second # [ 0.787063] RAS: Correctable Errors collector initialized.877test # [ 0.753715] registered taskstats version 1878test # [ 0.754745] Loading compiled-in X.509 certificates879second # [ 0.796139] clk: Disabling unused clocks880second # [ 0.796989] PM: genpd: Disabling unused power domains881test # [ 0.769455] Demotion targets for Node 0: null882test # [ 0.770479] Key type .fscrypt registered883test # [ 0.771289] Key type fscrypt-provisioning registered884test # [ 0.772383] ima: No TPM chip found, activating TPM-bypass!885test # [ 0.777462] ima: Allocated hash algorithm: sha1886test # [ 0.778399] ima: No architecture policies found887test # [ 0.781527] PM: Magic number: 14:786:93888test # [ 0.783071] RAS: Correctable Errors collector initialized.889test # [ 0.791154] clk: Disabling unused clocks890test # [ 0.791987] PM: genpd: Disabling unused power domains891second # [ 0.897499] Freeing initrd memory: 29616K892second # [ 0.900239] Freeing unused decrypted memory: 2028K893second # [ 0.902788] Freeing unused kernel image (initmem) memory: 3660K894second # [ 0.904068] Write protecting the kernel read-only data: 32768k895second # [ 0.905508] Freeing unused kernel image (text/rodata gap) memory: 296K896second # [ 0.907118] Freeing unused kernel image (rodata/data gap) memory: 684K897test # [ 0.890629] Freeing initrd memory: 29620K898test # [ 0.893343] Freeing unused decrypted memory: 2028K899test # [ 0.895873] Freeing unused kernel image (initmem) memory: 3660K900test # [ 0.897130] Write protecting the kernel read-only data: 32768k901test # [ 0.898557] Freeing unused kernel image (text/rodata gap) memory: 296K902test # [ 0.900153] Freeing unused kernel image (rodata/data gap) memory: 684K903second # [ 0.938960] x86/mm: Checked W+X mappings: passed, no W+X pages found.904second # [ 0.940240] Run /init as init process905second # [ 0.948517] systemd[1]: Inserted module 'autofs4'906second # [ 0.962978] fuse: init (API version 7.45)907second # [ 0.968535] ACPI: \_SB_.GSIG: Enabled at IRQ 22908test # [ 0.931999] x86/mm: Checked W+X mappings: passed, no W+X pages found.909test # [ 0.933285] Run /init as init process910second # [ 0.971001] ACPI: \_SB_.GSIH: Enabled at IRQ 23911second # [ 0.974058] ACPI: \_SB_.GSIE: Enabled at IRQ 20912second # [ 0.976209] ACPI: \_SB_.GSIF: Enabled at IRQ 21913test # [ 0.941777] systemd[1]: Inserted module 'autofs4'914second # [ 0.981375] virtiofs virtio5: discovered new tag: nix-store915second # [ 0.983357] virtiofs virtio5: virtio_fs_setup_dax: No cache capability916second # [ 0.990553] virtiofs virtio6: discovered new tag: shared917second # [ 0.992521] virtiofs virtio6: virtio_fs_setup_dax: No cache capability918test # [ 0.956506] fuse: init (API version 7.45)919second # [ 0.995746] virtiofs virtio7: discovered new tag: xchg920second # [ 0.997668] virtiofs virtio7: virtio_fs_setup_dax: No cache capability921test # [ 0.962130] ACPI: \_SB_.GSIG: Enabled at IRQ 22922test # [ 0.964664] ACPI: \_SB_.GSIH: Enabled at IRQ 23923test # [ 0.967715] ACPI: \_SB_.GSIE: Enabled at IRQ 20924test # [ 0.970391] ACPI: \_SB_.GSIF: Enabled at IRQ 21925test # [ 0.975691] virtiofs virtio5: discovered new tag: nix-store926test # [ 0.977698] virtiofs virtio5: virtio_fs_setup_dax: No cache capability927second # [ 1.015709] systemd[1]: Successfully made /usr/ read-only.928test # [ 0.984634] virtiofs virtio6: discovered new tag: shared929test # [ 0.986581] virtiofs virtio6: virtio_fs_setup_dax: No cache capability930test # [ 0.989909] virtiofs virtio7: discovered new tag: xchg931test # [ 0.991819] virtiofs virtio7: virtio_fs_setup_dax: No cache capability932test # [ 1.009696] systemd[1]: Successfully made /usr/ read-only.933second # [ 1.351739] 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)934second # [ 1.357480] systemd[1]: Detected virtualization kvm.935second # [ 1.358510] systemd[1]: Detected architecture x86-64.936second # [ 1.359551] systemd[1]: Running in initrd.937second # [ 1.360602] systemd[1]: Initializing machine ID from random generator.938second # [ 1.361963] systemd[1]: Hostname set to <second>.939test # [ 1.345760] 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)940test # [ 1.351461] systemd[1]: Detected virtualization kvm.941test # [ 1.352482] systemd[1]: Detected architecture x86-64.942test # [ 1.353523] systemd[1]: Running in initrd.943test # [ 1.354583] systemd[1]: Initializing machine ID from random generator.944test # [ 1.355939] systemd[1]: Hostname set to <test>.945second # [ 1.414345] systemd[1]: bpf-restrict-fs: LSM BPF program attached946second # [ 1.442090] systemd[1]: Queued start job for default target Initrd Default Target.947test # [ 1.408134] systemd[1]: bpf-restrict-fs: LSM BPF program attached948second # [ 1.446563] systemd[1]: Created slice Slice /system/modprobe.949second # [ 1.447927] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.950second # [ 1.449540] systemd[1]: Expecting device /dev/disk/by-label/nixos...951second # [ 1.450852] systemd[1]: Reached target Path Units.952second # [ 1.451902] systemd[1]: Reached target Slice Units.953second # [ 1.452957] systemd[1]: Reached target Swaps.954second # [ 1.453920] systemd[1]: Reached target Timer Units.955second # [ 1.455050] systemd[1]: Listening on D-Bus System Message Bus Socket.956second # [ 1.456482] systemd[1]: Listening on Journal Socket (/dev/log).957second # [ 1.457839] systemd[1]: Listening on Journal Sockets.958second # [ 1.459010] systemd[1]: Listening on udev Control Socket.959second # [ 1.468137] systemd[1]: Listening on udev Kernel Socket.960second # [ 1.469279] systemd[1]: Reached target Socket Units.961second # [ 1.471039] systemd[1]: Starting Create List of Static Device Nodes...962test # [ 1.435918] systemd[1]: Queued start job for default target Initrd Default Target.963test # [ 1.440542] systemd[1]: Created slice Slice /system/modprobe.964second # [ 1.477870] systemd[1]: Starting Load Kernel Module configfs...965test # [ 1.441896] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.966test # [ 1.443519] systemd[1]: Expecting device /dev/disk/by-label/nixos...967test # [ 1.444831] systemd[1]: Reached target Path Units.968test # [ 1.445890] systemd[1]: Reached target Slice Units.969test # [ 1.446940] systemd[1]: Reached target Swaps.970test # [ 1.447920] systemd[1]: Reached target Timer Units.971test # [ 1.449049] systemd[1]: Listening on D-Bus System Message Bus Socket.972test # [ 1.450485] systemd[1]: Listening on Journal Socket (/dev/log).973second # [ 1.487730] systemd[1]: Starting Journal Service...974test # [ 1.451836] systemd[1]: Listening on Journal Sockets.975second # [ 1.492497] systemd[1]: Starting Load Kernel Modules...976second # [ 1.494745] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os977test # [ 1.453004] systemd[1]: Listening on udev Control Socket.978test # [ 1.462099] systemd[1]: Listening on udev Kernel Socket.979second # [ 1.499027] systemd[1]: Starting Coldplug All udev Devices...980test # [ 1.463245] systemd[1]: Reached target Socket Units.981test # [ 1.465004] systemd[1]: Starting Create List of Static Device Nodes...982test # [ 1.471603] systemd[1]: Starting Load Kernel Module configfs...983second # [ 1.512561] systemd[1]: Finished Create List of Static Device Nodes.984test # [ 1.481823] systemd[1]: Starting Journal Service...985second # [ 1.520031] systemd[1]: modprobe@configfs.service: Deactivated successfully.986test # [ 1.486136] systemd[1]: Starting Load Kernel Modules...987second # [ 1.523040] systemd-journald[65]: Collecting audit messages is disabled.988test # [ 1.488707] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os989second # [ 1.527865] systemd[1]: Finished Load Kernel Module configfs.990test # [ 1.493946] systemd[1]: Starting Coldplug All udev Devices...991second # [ 1.532914] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config992second # [ 1.537998] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.993test # [ 1.506556] systemd[1]: Finished Create List of Static Device Nodes.994second # [ 1.544508] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...995second # [ 1.548463] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev996test # [ 1.513026] systemd[1]: modprobe@configfs.service: Deactivated successfully.997test # [ 1.517056] systemd-journald[65]: Collecting audit messages is disabled.998test # [ 1.520496] systemd[1]: Finished Load Kernel Module configfs.999test # [ 1.527894] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config1000test # [ 1.535790] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1001second # [ 1.574072] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1002test # [ 1.538901] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1003second # [ 1.580514] systemd[1]: Finished Load Kernel Modules.1004test # [ 1.544457] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1005second # [ 1.586320] systemd[1]: Starting Apply Kernel Variables...1006second # [ 1.598601] systemd[1]: Starting Create Static Device Nodes in /dev...1007test # [ 1.570260] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1008second # [ 1.613325] systemd[1]: Finished Apply Kernel Variables.1009test # [ 1.577524] systemd[1]: Finished Load Kernel Modules.1010test # [ 1.584604] systemd[1]: Starting Apply Kernel Variables...1011second # [ 1.624061] systemd[1]: Finished Create Static Device Nodes in /dev.1012second # [ 1.627666] systemd[1]: Reached target Preparation for Local File Systems.1013test # [ 1.594626] systemd[1]: Starting Create Static Device Nodes in /dev...1014second # [ 1.633518] systemd[1]: Reached target Local File Systems.1015second # [ 1.639506] systemd[1]: Starting Rule-based Manager for Device Events and Files...1016test # [ 1.609540] systemd[1]: Finished Apply Kernel Variables.1017test # [ 1.620042] systemd[1]: Finished Create Static Device Nodes in /dev.1018second # [ 1.657529] systemd[1]: Started Journal Service.1019second # [ 1.489240] systemd-modules-load[66]: Inserted module 'dm_mod'1020second # [ 1.490782] systemd-modules-load[66]: Inserted module 'virtio_balloon'1021test # [ 1.624652] systemd[1]: Reached target Preparation for Local File Systems.1022second # [ 1.495031] systemd-modules-load[66]: Inserted module 'virtio_gpu'1023test # [ 1.628523] systemd[1]: Reached target Local File Systems.1024second # [ 1.501191] systemd[1]: Starting Create System Files and Directories...1025test # [ 1.634500] systemd[1]: Starting Rule-based Manager for Device Events and Files...1026test # [ 1.481250] systemd-modules-load[66]: Inserted module 'dm_mod'1027test # [ 1.482723] systemd-modules-load[66]: Inserted module 'virtio_balloon'1028test # [ 1.652218] systemd[1]: Started Journal Service.1029test # [ 1.486381] systemd-modules-load[66]: Inserted module 'virtio_gpu'1030second # [ 1.523218] systemd-udevd[72]: Using default interface naming scheme 'v261'.1031second # [ 1.524522] systemd[1]: Finished Create System Files and Directories.1032test # [ 1.491273] systemd[1]: Starting Create System Files and Directories...1033second # [ 1.547348] systemd[1]: Started Rule-based Manager for Device Events and Files.1034test # [ 1.514239] systemd[1]: Finished Create System Files and Directories.1035test # [ 1.521307] systemd-udevd[73]: Using default interface naming scheme 'v261'.1036second # [ 1.563837] systemd[1]: Finished Coldplug All udev Devices.1037second # [ 1.564874] systemd[1]: Reached target System Initialization.1038second # [ 1.565898] systemd[1]: Reached target Basic System.1039test # [ 1.542070] systemd[1]: Started Rule-based Manager for Device Events and Files.1040test # [ 1.553660] systemd[1]: Finished Coldplug All udev Devices.1041test # [ 1.554638] systemd[1]: Reached target System Initialization.1042test # [ 1.555699] systemd[1]: Reached target Basic System.1043test # [ 1.875940] virtio_blk virtio2: 1/0/0 default/read/poll queues1044test # [ 1.889311] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1045second # [ 1.926909] virtio_blk virtio2: 1/0/0 default/read/poll queues1046second # [ 1.930653] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121047second # [ 1.935599] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1048second # [ 1.940619] serio: i8042 KBD port at 0x60,0x64 irq 11049second # [ 1.941395] serio: i8042 AUX port at 0x60,0x64 irq 121050second # [ 1.947917] ehci-pci 0000:00:1d.7: EHCI Host Controller1051second # [ 1.948740] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11052second # [ 1.950515] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001053second # [ 1.957468] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001054second # [ 1.958722] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181055second # [ 1.959980] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11056second # [ 1.961306] usb usb1: Product: EHCI Host Controller1057second # [ 1.962187] usb usb1: Manufacturer: Linux 6.18.56 ehci_hcd1058second # [ 1.963138] usb usb1: SerialNumber: 0000:00:1d.71059second # [ 1.964218] hub 1-0:1.0: USB hub found1060second # [ 1.965224] hub 1-0:1.0: 6 ports detected1061second # [ 1.969449] uhci_hcd 0000:00:1d.0: UHCI Host Controller1062second # [ 1.970255] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21063test # [ 1.933916] ehci-pci 0000:00:1d.7: EHCI Host Controller1064test # [ 1.934742] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11065test # [ 1.936533] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001066second # [ 1.976334] uhci_hcd 0000:00:1d.0: detected 2 ports1067test # [ 1.939654] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121068second # [ 1.978538] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801069test # [ 1.945460] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001070test # [ 1.946731] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181071test # [ 1.947969] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11072test # [ 1.951234] serio: i8042 KBD port at 0x60,0x64 irq 11073second # [ 1.988555] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181074test # [ 1.952333] usb usb1: Product: EHCI Host Controller1075second # [ 1.989816] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11076test # [ 1.953452] usb usb1: Manufacturer: Linux 6.18.56 ehci_hcd1077test # [ 1.954284] usb usb1: SerialNumber: 0000:00:1d.71078test # [ 1.956565] hub 1-0:1.0: USB hub found1079test # [ 1.958061] hub 1-0:1.0: 6 ports detected1080test # [ 1.959017] serio: i8042 AUX port at 0x60,0x64 irq 121081test # [ 1.961000] uhci_hcd 0000:00:1d.0: UHCI Host Controller1082test # [ 1.961825] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21083second # [ 2.006452] usb usb2: Product: UHCI Host Controller1084second # [ 2.007221] usb usb2: Manufacturer: Linux 6.18.56 uhci_hcd1085test # [ 1.976236] uhci_hcd 0000:00:1d.0: detected 2 ports1086test # [ 1.977338] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801087second # [ 2.015910] usb usb2: SerialNumber: 0000:00:1d.01088test # [ 1.980861] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181089test # [ 1.982127] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11090second # [ 1.851167] (udev-worker)[86]: Network interface NamePolicy= disabled on kernel command line.1091second # [ 1.854858] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line.1092second # [ 2.027613] hub 2-0:1.0: USB hub found1093second # [ 1.859372] systemd[1]: Starting Virtual Console Setup...1094second # [ 2.033336] hub 2-0:1.0: 2 ports detected1095test # [ 1.999448] usb usb2: Product: UHCI Host Controller1096test # [ 2.000221] usb usb2: Manufacturer: Linux 6.18.56 uhci_hcd1097test # [ 2.008349] usb usb2: SerialNumber: 0000:00:1d.01098test # [ 2.012174] SCSI subsystem initialized1099test # [ 2.012892] hub 2-0:1.0: USB hub found1100second # [ 2.049810] SCSI subsystem initialized1101second # [ 1.883213] systemd-vconsole-setup[95]: Configuration of first virtual console was skipped, ignoring remaining ones.1102test # [ 1.850155] systemd[1]: Starting Virtual Console Setup...1103second # [ 1.887219] systemd[1]: Finished Virtual Console Setup.1104test # [ 2.023449] hub 2-0:1.0: 2 ports detected1105test # [ 1.857795] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.1106second # [ 2.064565] uhci_hcd 0000:00:1d.1: UHCI Host Controller1107second # [ 2.065393] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31108second # [ 2.070514] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01109test # [ 1.867757] (udev-worker)[84]: Network interface NamePolicy= disabled on kernel command line.1110second # [ 2.084465] uhci_hcd 0000:00:1d.1: detected 2 ports1111second # [ 2.085329] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01112test # [ 1.881941] systemd-vconsole-setup[96]: Configuration of first virtual console was skipped, ignoring remaining ones.1113test # [ 2.052710] uhci_hcd 0000:00:1d.1: UHCI Host Controller1114test # [ 2.053568] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31115test # [ 1.886604] systemd[1]: Finished Virtual Console Setup.1116second # [ 2.092455] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181117second # [ 2.093698] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11118second # [ 1.927174] systemd[1]: Found device /dev/disk/by-label/nixos.1119second # [ 1.928316] systemd[1]: Reached target Initrd Root Device.1120second # [ 1.930068] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1121test # [ 1.899907] systemd[1]: Found device /dev/disk/by-label/nixos.1122test # [ 1.900958] systemd[1]: Reached target Initrd Root Device.1123test # [ 1.903219] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1124second # [ 2.109190] usb usb3: Product: UHCI Host Controller1125second # [ 2.111450] usb usb3: Manufacturer: Linux 6.18.56 uhci_hcd1126second # [ 2.112280] usb usb3: SerialNumber: 0000:00:1d.11127test # [ 2.079961] uhci_hcd 0000:00:1d.1: detected 2 ports1128second # [ 2.117674] hub 3-0:1.0: USB hub found1129second # [ 2.119864] hub 3-0:1.0: 2 ports detected1130test # [ 2.083519] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01131second # [ 1.955691] systemd-fsck[103]: nixos: clean, 12/65536 files, 13019/262144 blocks1132second # [ 2.127082] uhci_hcd 0000:00:1d.2: UHCI Host Controller1133second # [ 2.127944] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41134test # [ 2.092361] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01135second # [ 1.962542] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1136test # [ 2.095857] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181137second # [ 2.135876] uhci_hcd 0000:00:1d.2: detected 2 ports1138second # [ 2.137610] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01139test # [ 1.932661] systemd-fsck[103]: nixos: clean, 12/65536 files, 13019/262144 blocks1140test # [ 2.102927] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11141test # [ 1.937386] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1142second # [ 2.142607] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181143second # [ 2.145479] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11144test # [ 2.109524] usb usb3: Product: UHCI Host Controller1145test # [ 2.110338] usb usb3: Manufacturer: Linux 6.18.56 uhci_hcd1146test # [ 2.113481] usb usb3: SerialNumber: 0000:00:1d.11147test # [ 2.114743] hub 3-0:1.0: USB hub found1148second # [ 2.152849] usb usb4: Product: UHCI Host Controller1149second # [ 2.153725] usb usb4: Manufacturer: Linux 6.18.56 uhci_hcd1150second # [ 2.154891] usb usb4: SerialNumber: 0000:00:1d.21151test # [ 2.118543] hub 3-0:1.0: 2 ports detected1152second # [ 2.156967] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1153second # [ 2.158772] hub 4-0:1.0: USB hub found1154second # [ 2.159875] hub 4-0:1.0: 2 ports detected1155test # [ 2.123893] uhci_hcd 0000:00:1d.2: UHCI Host Controller1156test # [ 2.124721] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41157test # [ 2.128526] uhci_hcd 0000:00:1d.2: detected 2 ports1158test # [ 2.129385] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01159second # [ 2.169666] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1160test # [ 2.138463] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181161test # [ 2.139713] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11162second # [ 2.176977] ahci 0000:00:1f.2: flags: 64bit ncq only1163test # [ 2.145890] usb usb4: Product: UHCI Host Controller1164test # [ 2.147043] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1165test # [ 2.148284] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1166second # [ 2.185609] scsi host0: ahci1167test # [ 2.149690] usb usb4: Manufacturer: Linux 6.18.56 uhci_hcd1168second # [ 2.187373] scsi host1: ahci1169test # [ 2.150807] usb usb4: SerialNumber: 0000:00:1d.21170second # [ 2.188717] scsi host2: ahci1171test # [ 2.151880] hub 4-0:1.0: USB hub found1172test # [ 2.152857] ahci 0000:00:1f.2: flags: 64bit ncq only1173test # [ 2.153872] hub 4-0:1.0: 2 ports detected1174second # [ 2.191283] scsi host3: ahci1175second # [ 2.193284] scsi host4: ahci1176second # [ 2.194596] scsi host5: ahci1177second # [ 2.195195] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11178second # [ 2.198444] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11179second # [ 2.199691] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11180test # [ 2.163656] scsi host0: ahci1181second # [ 2.201075] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11182test # [ 2.165525] scsi host1: ahci1183second # [ 2.202398] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11184test # [ 2.166715] scsi host2: ahci1185second # [ 2.203674] usb 1-1: new high-speed USB device number 2 using ehci-pci1186test # [ 2.168170] scsi host3: ahci1187second # [ 2.204972] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11188test # [ 2.171465] scsi host4: ahci1189test # [ 2.176527] scsi host5: ahci1190test # [ 2.177161] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11191test # [ 2.182334] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11192test # [ 2.185812] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11193test # [ 2.187145] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11194test # [ 2.188488] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11195test # [ 2.189789] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11196test # [ 2.201513] usb 1-1: new high-speed USB device number 2 using ehci-pci1197second # [ 2.333246] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001198second # [ 2.334501] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101199second # [ 2.335823] usb 1-1: Product: QEMU USB Tablet1200second # [ 2.336641] usb 1-1: Manufacturer: QEMU1201second # [ 2.337276] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11202second # [ 2.347796] hid: raw HID events driver (C) Jiri Kosina1203test # [ 2.332401] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001204test # [ 2.333649] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101205test # [ 2.334963] usb 1-1: Product: QEMU USB Tablet1206test # [ 2.335777] usb 1-1: Manufacturer: QEMU1207test # [ 2.336535] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11208test # [ 2.346912] hid: raw HID events driver (C) Jiri Kosina1209second # [ 2.339522] systemd[1]: Mounting /sysroot...1210second # [ 2.513649] ata4: SATA link down (SStatus 0 SControl 300)1211second # [ 2.515611] ata6: SATA link down (SStatus 0 SControl 300)1212second # [ 2.516650] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1213second # [ 2.518527] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001214second # [ 2.519385] ata3.00: applying bridge limits1215second # [ 2.521605] ata5: SATA link down (SStatus 0 SControl 300)1216second # [ 2.522713] ata1: SATA link down (SStatus 0 SControl 300)1217second # [ 2.523832] ata2: SATA link down (SStatus 0 SControl 300)1218second # [ 2.524896] ata3.00: configured for UDMA/1001219second # [ 2.527731] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51220test # [ 2.495790] ata1: SATA link down (SStatus 0 SControl 300)1221test # [ 2.334835] systemd[1]: Mounting /sysroot...1222test # [ 2.504664] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1223test # [ 2.505688] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001224test # [ 2.508350] ata3.00: applying bridge limits1225test # [ 2.509534] ata3.00: configured for UDMA/1001226test # [ 2.510532] ata2: SATA link down (SStatus 0 SControl 300)1227test # [ 2.511589] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51228test # [ 2.513769] ata5: SATA link down (SStatus 0 SControl 300)1229test # [ 2.514787] ata4: SATA link down (SStatus 0 SControl 300)1230test # [ 2.515808] ata6: SATA link down (SStatus 0 SControl 300)1231second # [ 2.580521] usbcore: registered new interface driver usbhid1232second # [ 2.581366] usbhid: USB HID core driver1233test # [ 2.551612] usbcore: registered new interface driver usbhid1234test # [ 2.552477] usbhid: USB HID core driver1235second # [ 2.601729] 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.567097] 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/input21237second # [ 2.604814] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01238second # [ 2.608699] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1239test # [ 2.572494] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1240test # [ 2.573617] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01241second # [ 2.611136] EXT4-fs (vda): mounted filesystem c6576922-c10b-42fc-8019-c6b277bf9254 r/w with ordered data mode. Quota mode: none.1242second # [ 2.448718] systemd[1]: Mounted /sysroot.1243second # [ 2.450475] systemd[1]: Reached target Initrd Root File System.1244second # [ 2.453234] systemd[1]: Starting Mountpoints Configured in the Real Root...1245test # [ 2.586537] cdrom: Uniform CD-ROM driver Revision: 3.201246second # [ 2.627376] cdrom: Uniform CD-ROM driver Revision: 3.201247second # [ 2.466745] systemd-sysroot-fstab-check[138]: /sysroot should be mounted in the initrd, will request daemon-reload.1248second # [ 2.471064] systemd[1]: Reload requested from client PID 138 ('systemd-sysroot') (unit initrd-parse-etc.service)...1249second # [ 2.473156] systemd[1]: Reloading...1250test # [ 2.615099] EXT4-fs (vda): mounted filesystem 9ef198f3-b6f6-4756-89a7-ffe6a5fbf77f r/w with ordered data mode. Quota mode: none.1251test # [ 2.452113] systemd[1]: Mounted /sysroot.1252test # [ 2.453510] systemd[1]: Reached target Initrd Root File System.1253test # [ 2.455506] systemd[1]: Starting Mountpoints Configured in the Real Root...1254test # [ 2.467172] systemd-sysroot-fstab-check[136]: /sysroot should be mounted in the initrd, will request daemon-reload.1255test # [ 2.470272] systemd[1]: Reload requested from client PID 136 ('systemd-sysroot') (unit initrd-parse-etc.service)...1256test # [ 2.472809] systemd[1]: Reloading...1257second # [ 2.533048] systemd[1]: Reloading finished in 58 ms.1258second # [ 2.542325] systemd-sysroot-fstab-check[138]: Requesting initrd-fs.target/start/replace...1259second # [ 2.547153] systemd-sysroot-fstab-check[138]: Requesting swap.target/start/replace...1260second # [ 2.552686] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1261second # [ 2.554653] systemd[1]: Finished Mountpoints Configured in the Real Root.1262second # [ 2.555884] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1263test # [ 2.529767] systemd[1]: Reloading finished in 57 ms.1264test # [ 2.537416] systemd-sysroot-fstab-check[136]: Requesting initrd-fs.target/start/replace...1265test # [ 2.542154] systemd-sysroot-fstab-check[136]: Requesting swap.target/start/replace...1266test # [ 2.544572] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1267test # [ 2.546248] systemd[1]: Finished Mountpoints Configured in the Real Root.1268test # [ 2.548181] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1269second # [ 3.340074] systemd[1]: Mounting /sysroot/nix/.ro-store...1270second # [ 3.343092] systemd[1]: Mounting /sysroot/nix/.rw-store...1271second # [ 3.346161] systemd[1]: Mounting /sysroot/run...1272second # [ 3.348862] systemd[1]: Mounting /sysroot/tmp/shared...1273second # [ 3.355143] systemd[1]: Mounting /sysroot/tmp/xchg...1274test # [ 3.335666] systemd[1]: Mounting /sysroot/nix/.ro-store...1275test # [ 3.339142] systemd[1]: Mounting /sysroot/nix/.rw-store...1276test # [ 3.342133] systemd[1]: Mounting /sysroot/run...1277second # [ 3.379199] systemd[1]: Mounted /sysroot/nix/.ro-store.1278second # [ 3.382293] systemd[1]: Mounted /sysroot/nix/.rw-store.1279test # [ 3.347271] systemd[1]: Mounting /sysroot/tmp/shared...1280second # [ 3.383371] systemd[1]: Mounted /sysroot/run.1281second # [ 3.384342] systemd[1]: Mounted /sysroot/tmp/shared.1282second # [ 3.386473] systemd[1]: Mounted /sysroot/tmp/xchg.1283test # [ 3.353853] systemd[1]: Mounting /sysroot/tmp/xchg...1284second # [ 3.389702] systemd[1]: Starting rw-sysroot-nix-store.service...1285second # [ 3.399737] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1286second # [ 3.401567] systemd[1]: Finished rw-sysroot-nix-store.service.1287test # [ 3.369397] systemd[1]: Mounted /sysroot/nix/.rw-store.1288test # [ 3.374817] systemd[1]: Mounted /sysroot/nix/.ro-store.1289test # [ 3.377663] systemd[1]: Mounted /sysroot/run.1290test # [ 3.378763] systemd[1]: Mounted /sysroot/tmp/shared.1291test # [ 3.380192] systemd[1]: Mounted /sysroot/tmp/xchg.1292test # [ 3.383763] systemd[1]: Starting rw-sysroot-nix-store.service...1293test # [ 3.392175] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1294test # [ 3.394109] systemd[1]: Finished rw-sysroot-nix-store.service.1295second # [ 4.339818] systemd[1]: Mounting /sysroot/nix/store...1296second # [ 4.356648] systemd[1]: Mounted /sysroot/nix/store.1297second # [ 4.358812] systemd[1]: Reached target Initrd File Systems.1298second # [ 4.360125] systemd[1]: Starting Find NixOS closure...1299second # [ 4.363138] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1300test # [ 4.335201] systemd[1]: Mounting /sysroot/nix/store...1301second # [ 4.379600] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1302second # [ 4.384391] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1303test # [ 4.352418] systemd[1]: Mounted /sysroot/nix/store.1304second # [ 4.388984] systemd[1]: Finished Find NixOS closure.1305test # [ 4.353911] systemd[1]: Reached target Initrd File Systems.1306second # [ 4.390503] systemd[1]: Reached target Initrd Default Target.1307test # [ 4.355868] systemd[1]: Starting Find NixOS closure...1308second # [ 4.392394] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1309test # [ 4.358571] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1310second # [ 4.403872] systemd[1]: Stopped target Initrd Default Target.1311second # [ 4.405556] systemd[1]: Stopped target Basic System.1312second # [ 4.406808] systemd[1]: Stopped target Initrd Root Device.1313second # [ 4.409075] systemd[1]: Stopped target Path Units.1314second # [ 4.410071] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1315second # [ 4.411443] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1316second # [ 4.412970] systemd[1]: Stopped target Slice Units.1317test # [ 4.377089] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1318second # [ 4.413974] systemd[1]: Stopped target Socket Units.1319second # [ 4.415296] systemd[1]: Stopped target System Initialization.1320test # [ 4.379113] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1321second # [ 4.416707] systemd[1]: Stopped target Swaps.1322second # [ 4.418185] systemd[1]: Stopped target Timer Units.1323second # [ 4.419308] systemd[1]: dbus.socket: Deactivated successfully.1324test # [ 4.383913] systemd[1]: Finished Find NixOS closure.1325second # [ 4.420816] systemd[1]: Closed D-Bus System Message Bus Socket.1326test # [ 4.385457] systemd[1]: Reached target Initrd Default Target.1327second # [ 4.422096] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1328second # [ 4.424164] systemd[1]: Stopped Find NixOS closure.1329test # [ 4.388247] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1330second # [ 4.425195] systemd[1]: Starting rw-sysroot-nix-store.service...1331second # [ 4.426481] systemd[1]: systemd-sysctl.service: Deactivated successfully.1332second # [ 4.428159] systemd[1]: Stopped Apply Kernel Variables.1333second # [ 4.429266] systemd[1]: systemd-modules-load.service: Deactivated successfully.1334second # [ 4.431158] systemd[1]: Stopped Load Kernel Modules.1335second # [ 4.432166] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1336second # [ 4.433817] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1337test # [ 4.398644] systemd[1]: Stopped target Initrd Default Target.1338second # [ 4.435600] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1339test # [ 4.400294] systemd[1]: Stopped target Basic System.1340second # [ 4.437203] systemd[1]: Stopped Create System Files and Directories.1341test # [ 4.401610] systemd[1]: Stopped target Initrd Root Device.1342second # [ 4.438841] systemd[1]: Stopped target Local File Systems.1343test # [ 4.403144] systemd[1]: Stopped target Path Units.1344test # [ 4.404194] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1345second # [ 4.440400] systemd[1]: Stopped target Preparation for Local File Systems.1346test # [ 4.405955] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1347second # [ 4.442481] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1348test # [ 4.407737] systemd[1]: Stopped target Slice Units.1349second # [ 4.444161] systemd[1]: Stopped Coldplug All udev Devices.1350test # [ 4.409191] systemd[1]: Stopped target Socket Units.1351second # [ 4.445896] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1352test # [ 4.410386] systemd[1]: Stopped target System Initialization.1353test # [ 4.411807] systemd[1]: Stopped target Swaps.1354second # [ 4.447605] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1355test # [ 4.413174] systemd[1]: Stopped target Timer Units.1356test # [ 4.414179] systemd[1]: dbus.socket: Deactivated successfully.1357second # [ 4.450166] systemd[1]: Stopped Virtual Console Setup.1358test # [ 4.415559] systemd[1]: Closed D-Bus System Message Bus Socket.1359test # [ 4.417166] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1360test # [ 4.419148] systemd[1]: Stopped Find NixOS closure.1361test # [ 4.420131] systemd[1]: Starting rw-sysroot-nix-store.service...1362test # [ 4.421287] systemd[1]: systemd-sysctl.service: Deactivated successfully.1363test # [ 4.422737] systemd[1]: Stopped Apply Kernel Variables.1364second # [ 4.458634] systemd[1]: initrd-cleanup.service: Deactivated successfully.1365test # [ 4.424229] systemd[1]: systemd-modules-load.service: Deactivated successfully.1366test # [ 4.426154] systemd[1]: Stopped Load Kernel Modules.1367second # [ 4.462205] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1368test # [ 4.427171] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1369second # [ 4.463508] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1370test # [ 4.428579] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1371second # [ 4.464981] systemd[1]: Finished rw-sysroot-nix-store.service.1372test # [ 4.430239] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1373second # [ 4.466556] systemd[1]: systemd-udevd.service: Deactivated successfully.1374test # [ 4.432231] systemd[1]: Stopped Create System Files and Directories.1375second # [ 4.468364] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1376test # [ 4.434154] systemd[1]: Stopped target Local File Systems.1377test # [ 4.435255] systemd[1]: Stopped target Preparation for Local File Systems.1378test # [ 4.436801] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1379second # [ 4.473088] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1380second # [ 4.474462] systemd[1]: Closed udev Control Socket.1381second # [ 4.475716] systemd[1]: Starting Cleanup udev Database...1382test # [ 4.440109] systemd[1]: Stopped Coldplug All udev Devices.1383second # [ 4.476824] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1384test # [ 4.441191] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1385second # [ 4.479141] systemd[1]: Stopped Create Static Device Nodes in /dev.1386test # [ 4.442982] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1387second # [ 4.480883] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1388test # [ 4.445871] systemd[1]: Stopped Virtual Console Setup.1389second # [ 4.482338] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1390second # [ 4.484169] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1391second # [ 4.485453] systemd[1]: Stopped Create List of Static Device Nodes.1392test # [ 4.453123] systemd[1]: initrd-cleanup.service: Deactivated successfully.1393test # [ 4.455110] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1394test # [ 4.457372] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1395test # [ 4.458850] systemd[1]: Finished rw-sysroot-nix-store.service.1396test # [ 4.460934] systemd[1]: systemd-udevd.service: Deactivated successfully.1397second # [ 4.496849] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1398test # [ 4.462282] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1399second # [ 4.498881] systemd[1]: Finished Cleanup udev Database.1400second # [ 4.500460] systemd[1]: Reached target Switch Root.1401second # [ 4.502195] systemd[1]: Starting NixOS Activation...1402test # [ 4.466501] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1403test # [ 4.467948] systemd[1]: Closed udev Control Socket.1404test # [ 4.470086] systemd[1]: Starting Cleanup udev Database...1405test # [ 4.471169] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1406test # [ 4.473166] systemd[1]: Stopped Create Static Device Nodes in /dev.1407test # [ 4.474347] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1408test # [ 4.475815] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1409test # [ 4.477395] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1410test # [ 4.479160] systemd[1]: Stopped Create List of Static Device Nodes.1411test # [ 4.491826] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1412test # [ 4.493858] systemd[1]: Finished Cleanup udev Database.1413test # [ 4.495448] systemd[1]: Reached target Switch Root.1414test # [ 4.497316] systemd[1]: Starting NixOS Activation...1415second # [ 4.552106] initrd-nixos-activation-start[190]: booting system configuration /nix/store/0wyiskhdjxmy71yz8w5y37l1bajyffw1-nixos-system-second-test1416second # [ 4.573843] initrd-nixos-activation-start[190]: running activation script...1417test # [ 4.546527] initrd-nixos-activation-start[187]: booting system configuration /nix/store/2rsgqqpdal2qkyav895xirhgy1vpy2qp-nixos-system-test-test1418test # [ 4.568323] initrd-nixos-activation-start[187]: running activation script...1419second # [ 4.719832] initrd-nixos-activation-start[213]: setting up /etc...1420test # [ 4.710713] initrd-nixos-activation-start[210]: setting up /etc...1421second # [ 4.794110] initrd-nixos-activation-start[213]: setting up age secrets...1422test # [ 4.783696] initrd-nixos-activation-start[210]: setting up age secrets...1423second # [ 4.867194] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1424second # [ 4.869204] systemd[1]: Finished NixOS Activation.1425second # [ 4.871394] systemd[1]: Starting Switch Root...1426second # [ 4.881688] systemd[1]: Switching root.1427test # [ 4.882995] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1428test # [ 4.885083] systemd[1]: Finished NixOS Activation.1429test # [ 4.887147] systemd[1]: Starting Switch Root...1430test # [ 4.898294] systemd[1]: Switching root.1431second # [ 5.174345] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).1432test # [ 5.188219] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).1433second # [ 5.255497] NET: Registered PF_VSOCK protocol family1434test # [ 5.240260] NET: Registered PF_VSOCK protocol family1435second # [ 5.606657] 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)1436second # [ 5.611313] systemd[1]: Detected virtualization kvm.1437second # [ 5.612203] systemd[1]: Detected architecture x86-64.1438second # [ 5.613118] systemd[1]: Detected first boot.1439second # [ 5.614599] systemd[1]: Initializing machine ID from random generator.1440test # [ 5.591560] 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)1441test # [ 5.596212] systemd[1]: Detected virtualization kvm.1442test # [ 5.597110] systemd[1]: Detected architecture x86-64.1443test # [ 5.598011] systemd[1]: Detected first boot.1444test # [ 5.599456] systemd[1]: Initializing machine ID from random generator.1445second # [ 5.680638] systemd[1]: bpf-restrict-fs: LSM BPF program attached1446test # [ 5.664036] systemd[1]: bpf-restrict-fs: LSM BPF program attached1447second # [ 5.738029] systemd[1]: Applying preset policy.1448test # [ 5.721196] systemd[1]: Applying preset policy.1449second # [ 5.886799] systemd[1]: Populated /etc with preset unit settings.1450test # [ 5.871329] systemd[1]: Populated /etc with preset unit settings.1451second # [ 6.052857] systemd[1]: initrd-switch-root.service: Deactivated successfully.1452second # [ 6.054354] systemd[1]: Stopped initrd-switch-root.service.1453second # [ 6.056687] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1454second # [ 6.058764] systemd[1]: Created slice Slice /system/getty.1455second # [ 6.060093] systemd[1]: Created slice User and Session Slice.1456second # [ 6.061108] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1457second # [ 6.062474] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1458second # [ 6.063669] systemd[1]: Expecting device /dev/hvc0...1459second # [ 6.064511] systemd[1]: Expecting device /dev/ttyS0...1460second # [ 6.065356] systemd[1]: Reached target Local Encrypted Volumes.1461second # [ 6.066308] systemd[1]: Stopped target initrd-fs.target.1462test # [ 6.037868] systemd[1]: initrd-switch-root.service: Deactivated successfully.1463second # [ 6.067177] systemd[1]: Stopped target initrd-root-fs.target.1464second # [ 6.075917] systemd[1]: Stopped target initrd-switch-root.target.1465test # [ 6.039340] systemd[1]: Stopped initrd-switch-root.service.1466second # [ 6.076900] systemd[1]: Reached target Virtual Machines and Containers.1467second # [ 6.077940] systemd[1]: Reached target Path Units.1468second # [ 6.078754] systemd[1]: Reached target Remote File Systems.1469test # [ 6.041696] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1470second # [ 6.079661] systemd[1]: Reached target Slice Units.1471second # [ 6.080504] systemd[1]: Reached target Swaps.1472test # [ 6.043751] systemd[1]: Created slice Slice /system/getty.1473test # [ 6.045093] systemd[1]: Created slice User and Session Slice.1474second # [ 6.082088] systemd[1]: Listening on Query the User Interactively for a Password.1475test # [ 6.046118] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1476second # [ 6.084238] systemd[1]: Listening on Process Core Dump Socket.1477test # [ 6.047485] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1478test # [ 6.048672] systemd[1]: Expecting device /dev/hvc0...1479second # [ 6.085916] systemd[1]: Listening on Credential Encryption/Decryption.1480test # [ 6.049510] systemd[1]: Expecting device /dev/ttyS0...1481test # [ 6.050347] systemd[1]: Reached target Local Encrypted Volumes.1482second # [ 6.087695] systemd[1]: Listening on Factory Reset Management.1483test # [ 6.051295] systemd[1]: Stopped target initrd-fs.target.1484second # [ 6.088733] systemd[1]: Listening on Hostname Service Socket.1485second # [ 6.091001] systemd[1]: Starting Journal Log Access Socket...1486second # [ 6.092350] systemd[1]: Listening on Journal Audit Socket.1487second # [ 6.094620] systemd[1]: Listening on Console Output Muting Service Socket.1488second # [ 6.095820] systemd[1]: Listening on Network Management Metrics Varlink Socket.1489test # [ 6.052171] systemd[1]: Stopped target initrd-root-fs.target.1490second # [ 6.097126] systemd[1]: Listening on Network Management Varlink Socket.1491test # [ 6.060918] systemd[1]: Stopped target initrd-switch-root.target.1492second # [ 6.098278] systemd[1]: Listening on Network Management Netlink Socket.1493test # [ 6.061903] systemd[1]: Reached target Virtual Machines and Containers.1494test # [ 6.062939] systemd[1]: Reached target Path Units.1495second # [ 6.099480] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1496test # [ 6.063769] systemd[1]: Reached target Remote File Systems.1497second # [ 6.100685] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1498test # [ 6.064681] systemd[1]: Reached target Slice Units.1499test # [ 6.065529] systemd[1]: Reached target Swaps.1500second # [ 6.102114] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1501test # [ 6.067103] systemd[1]: Listening on Query the User Interactively for a Password.1502second # [ 6.105709] systemd[1]: Listening on Disk Repartitioning Service Socket.1503test # [ 6.069259] systemd[1]: Listening on Process Core Dump Socket.1504second # [ 6.106856] systemd[1]: Listening on Resolve Monitor Varlink Socket.1505test # [ 6.070934] systemd[1]: Listening on Credential Encryption/Decryption.1506second # [ 6.107942] systemd[1]: Listening on Resolve Service Varlink Socket.1507second # [ 6.109027] systemd[1]: Listening on udev Control Socket.1508test # [ 6.072723] systemd[1]: Listening on Factory Reset Management.1509second # [ 6.110003] systemd[1]: Listening on udev Varlink Socket.1510test # [ 6.073750] systemd[1]: Listening on Hostname Service Socket.1511second # [ 6.111860] systemd[1]: Mounting Huge Pages File System...1512test # [ 6.076043] systemd[1]: Starting Journal Log Access Socket...1513test # [ 6.077463] systemd[1]: Listening on Journal Audit Socket.1514second # [ 6.114551] systemd[1]: Mounting POSIX Message Queue File System...1515test # [ 6.079766] systemd[1]: Listening on Console Output Muting Service Socket.1516test # [ 6.080964] systemd[1]: Listening on Network Management Metrics Varlink Socket.1517test # [ 6.082288] systemd[1]: Listening on Network Management Varlink Socket.1518test # [ 6.083474] systemd[1]: Listening on Network Management Netlink Socket.1519second # [ 6.120700] systemd[1]: Mounting Kernel Debug File System...1520test # [ 6.084646] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1521test # [ 6.085853] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1522test # [ 6.087284] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1523second # [ 6.125022] systemd[1]: Mounting Kernel Trace File System...1524test # [ 6.090868] systemd[1]: Listening on Disk Repartitioning Service Socket.1525test # [ 6.092015] systemd[1]: Listening on Resolve Monitor Varlink Socket.1526test # [ 6.093101] systemd[1]: Listening on Resolve Service Varlink Socket.1527test # [ 6.094205] systemd[1]: Listening on udev Control Socket.1528test # [ 6.095200] systemd[1]: Listening on udev Varlink Socket.1529second # [ 6.133185] systemd[1]: Starting Create List of Static Device Nodes...1530test # [ 6.097096] systemd[1]: Mounting Huge Pages File System...1531test # [ 6.099741] systemd[1]: Mounting POSIX Message Queue File System...1532test # [ 6.105668] systemd[1]: Mounting Kernel Debug File System...1533second # [ 6.142791] systemd[1]: Starting Load Kernel Module configfs...1534test # [ 6.111670] systemd[1]: Mounting Kernel Trace File System...1535second # [ 6.148451] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1536test # [ 6.120154] systemd[1]: Starting Create List of Static Device Nodes...1537second # [ 6.160816] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1538second # [ 6.165870] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1539test # [ 6.132447] systemd[1]: Starting Load Kernel Module configfs...1540test # [ 6.133373] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1541second # [ 6.174535] systemd[1]: Mounting FUSE Control File System...1542second # [ 6.177497] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671543test # [ 6.146898] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1544second # [ 6.185797] systemd[1]: Starting Journal Service...1545test # [ 6.149527] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1546second # [ 6.190688] systemd[1]: Starting Load Kernel Modules...1547second # [ 6.197336] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1548test # [ 6.161664] systemd[1]: Mounting FUSE Control File System...1549test # [ 6.164565] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671550test # [ 6.171849] systemd[1]: Starting Journal Service...1551second # [ 6.208497] systemd[1]: Starting Remount Root and Kernel File Systems...1552second # [ 6.210681] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1553test # [ 6.176200] systemd[1]: Starting Load Kernel Modules...1554second # [ 6.220551] systemd-journald[317]: Collecting audit messages is enabled.1555second # [ 6.222598] systemd[1]: Starting Coldplug All udev Devices...1556test # [ 6.186767] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1557test # [ 6.193636] systemd[1]: Starting Remount Root and Kernel File Systems...1558second # [ 6.232782] systemd[1]: Listening on Journal Log Access Socket.1559second # [ 6.238335] systemd[1]: Mounted Huge Pages File System.1560test # [ 6.201599] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1561second # [ 6.241851] systemd[1]: Mounted POSIX Message Queue File System.1562second # [ 6.245943] loop: module loaded1563test # [ 6.209375] systemd-journald[335]: Collecting audit messages is enabled.1564second # [ 6.247949] systemd[1]: Mounted Kernel Debug File System.1565second # [ 6.081660] systemd[1]: Queued start job for default target Multi-User System.1566test # [ 6.214565] systemd[1]: Starting Coldplug All udev Devices...1567second # [ 6.085401] systemd[1]: systemd-journald.service: Deactivated successfully.1568second # [ 6.256049] systemd[1]: Started Journal Service.1569second # [ 6.087914] systemd-modules-load[318]: Module 'ctr' is built in1570second # [ 6.090494] systemd-modules-load[318]: Inserted module 'loop'1571test # [ 6.225949] systemd[1]: Listening on Journal Log Access Socket.1572second # [ 6.096254] systemd[1]: Mounted Kernel Trace File System.1573test # [ 6.230144] loop: module loaded1574second # [ 6.100254] systemd[1]: Finished Create List of Static Device Nodes.1575test # [ 6.233639] systemd[1]: Mounted Huge Pages File System.1576second # [ 6.101412] systemd[1]: modprobe@configfs.service: Deactivated successfully.1577test # [ 6.235664] systemd[1]: Mounted POSIX Message Queue File System.1578second # [ 6.106883] systemd[1]: Finished Load Kernel Module configfs.1579test # [ 6.239607] systemd[1]: Started Journal Service.1580second # [ 6.107961] systemd[1]: Mounted FUSE Control File System.1581test # [ 6.072307] systemd[1]: Queued start job for default target Multi-User System.1582test # [ 6.073841] systemd[1]: systemd-journald.service: Deactivated successfully.1583test # [ 6.076614] systemd-modules-load[336]: Module 'ctr' is built in1584second # [ 6.112996] systemd[1]: Finished Load Kernel Modules.1585second # [ 6.283353] EXT4-fs (vda): re-mounted c6576922-c10b-42fc-8019-c6b277bf9254.1586test # [ 6.080467] systemd-modules-load[336]: Inserted module 'loop'1587test # [ 6.081728] systemd[1]: Mounted Kernel Debug File System.1588test # [ 6.085300] systemd[1]: Mounted Kernel Trace File System.1589second # [ 6.122139] systemd[1]: Finished Remount Root and Kernel File Systems.1590test # [ 6.090327] systemd[1]: Finished Create List of Static Device Nodes.1591second # [ 6.126735] systemd[1]: Listening on Disk Image Download Service Socket.1592test # [ 6.092446] systemd[1]: modprobe@configfs.service: Deactivated successfully.1593second # [ 6.130668] systemd[1]: Mounting Kernel Configuration File System...1594test # [ 6.096485] systemd[1]: Finished Load Kernel Module configfs.1595test # [ 6.097569] systemd[1]: Mounted FUSE Control File System.1596second # [ 6.134502] systemd[1]: Starting Firewall...1597test # [ 6.266910] EXT4-fs (vda): re-mounted 9ef198f3-b6f6-4756-89a7-ffe6a5fbf77f.1598second # [ 6.135483] systemd-oomd[320]: No swap; memory pressure usage will be degraded1599test # [ 6.103426] systemd[1]: Finished Load Kernel Modules.1600second # [ 6.140945] systemd[1]: Starting Flush Journal to Persistent Storage...1601second # [ 6.142332] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1602test # [ 6.111218] systemd[1]: Finished Remount Root and Kernel File Systems.1603test # [ 6.115953] systemd[1]: Listening on Disk Image Download Service Socket.1604second # [ 6.153751] systemd[1]: Starting Load/Save OS Random Seed...1605test # [ 6.121119] systemd[1]: Mounting Kernel Configuration File System...1606test # [ 6.125809] systemd[1]: Starting Firewall...1607second # [ 6.163626] systemd[1]: Starting Apply Kernel Variables...1608test # [ 6.127346] systemd-oomd[338]: No swap; memory pressure usage will be degraded1609second # [ 6.171757] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1610second # [ 6.174371] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1611test # [ 6.139035] systemd[1]: Starting Flush Journal to Persistent Storage...1612second # [ 6.175804] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1613test # [ 6.140242] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1614test # [ 6.146851] systemd[1]: Starting Load/Save OS Random Seed...1615test # [ 6.152076] systemd[1]: Starting Apply Kernel Variables...1616second # [ 6.361632] systemd-journald[317]: Received client request to flush runtime journal.1617test # [ 6.156968] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1618test # [ 6.158241] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1619test # [ 6.159653] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1620test # [ 6.364627] systemd-journald[335]: Received client request to flush runtime journal.1621second # [ 6.377425] systemd[1]: Finished Load/Save OS Random Seed.1622second # [ 6.381260] systemd[1]: Reached target First Boot Complete.1623second # [ 6.383269] systemd[1]: Mounted Kernel Configuration File System.1624second # [ 6.384513] systemd[1]: Finished Apply Kernel Variables.1625second # [ 6.386384] systemd[1]: Starting Network Name Resolution...1626second # [ 6.388910] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1627second # [ 6.390311] systemd[1]: Starting Create Static Device Nodes in /dev...1628second # [ 6.391572] systemd[1]: Finished Flush Journal to Persistent Storage.1629second # [ 6.401538] systemd-resolved[346]: Positive Trust Anchors:1630second # [ 6.404228] systemd-resolved[346]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1631second # [ 6.405921] systemd-resolved[346]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161632second # [ 6.407941] systemd-resolved[346]: 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 test1633test # [ 6.378290] systemd[1]: Mounted Kernel Configuration File System.1634test # [ 6.379761] systemd[1]: Finished Load/Save OS Random Seed.1635test # [ 6.384485] systemd[1]: Reached target First Boot Complete.1636test # [ 6.385665] systemd[1]: Finished Apply Kernel Variables.1637test # [ 6.388153] systemd[1]: Starting Network Name Resolution...1638test # [ 6.389225] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1639test # [ 6.390441] systemd[1]: Starting Create Static Device Nodes in /dev...1640test # [ 6.392296] systemd[1]: Finished Flush Journal to Persistent Storage.1641second # [ 6.434162] systemd[1]: Finished Create Static Device Nodes in /dev.1642test # [ 6.399511] systemd-resolved[365]: Positive Trust Anchors:1643second # [ 6.435277] systemd[1]: Reached target Preparation for Local File Systems.1644test # [ 6.401653] systemd-resolved[365]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1645second # [ 6.439853] systemd[1]: Starting Rule-based Manager for Device Events and Files...1646test # [ 6.404223] systemd-resolved[365]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161647test # [ 6.406069] 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 test1648test # [ 6.437218] systemd[1]: Finished Create Static Device Nodes in /dev.1649test # [ 6.438644] systemd[1]: Reached target Preparation for Local File Systems.1650test # [ 6.442498] systemd[1]: Starting Rule-based Manager for Device Events and Files...1651second # [ 6.483172] systemd[1]: Finished Coldplug All udev Devices.1652second # [ 6.486443] systemd-resolved[346]: Using system hostname 'second'.1653second # [ 6.490661] systemd[1]: Started Network Name Resolution.1654second # [ 6.498584] systemd-udevd[360]: Using default interface naming scheme 'v261'.1655test # [ 6.472485] systemd[1]: Finished Coldplug All udev Devices.1656test # [ 6.479268] systemd-resolved[365]: Using system hostname 'test'.1657test # [ 6.482414] systemd[1]: Started Network Name Resolution.1658test # [ 6.499907] systemd-udevd[380]: Using default interface naming scheme 'v261'.1659second # [ 6.547080] systemd[1]: Started Rule-based Manager for Device Events and Files.1660test # [ 6.549341] systemd[1]: Started Rule-based Manager for Device Events and Files.1661second # [ 6.660986] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1662test # [ 6.666090] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1663second # [ 6.729902] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1664second # [ 6.757523] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1665test # [ 6.733911] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1666second # [ 6.773524] (udev-worker)[387]: Network interface NamePolicy= disabled on kernel command line.1667second # [ 6.783690] (udev-worker)[393]: Network interface NamePolicy= disabled on kernel command line.1668test # [ 6.763682] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1669test # [ 6.784629] (udev-worker)[407]: Network interface NamePolicy= disabled on kernel command line.1670test # [ 6.788488] (udev-worker)[406]: Network interface NamePolicy= disabled on kernel command line.1671second # [ 6.889061] systemd[1]: Mounting /run/wrappers...1672test # [ 6.873254] systemd[1]: Mounting /run/wrappers...1673second # [ 6.923593] systemd[1]: Mounted /run/wrappers.1674second # [ 6.924744] systemd[1]: Reached target Local File Systems.1675second # [ 6.927190] systemd[1]: Listening on Boot Loader Control Service Socket.1676second # [ 6.931104] systemd[1]: Starting register-nix-paths.service...1677test # [ 6.899037] systemd[1]: Mounted /run/wrappers.1678second # [ 6.935064] systemd[1]: Starting Create SUID/SGID Wrappers...1679test # [ 6.899898] systemd[1]: Reached target Local File Systems.1680second # [ 6.936092] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1681test # [ 6.902630] systemd[1]: Listening on Boot Loader Control Service Socket.1682second # [ 6.940470] systemd[1]: Starting Save Transient machine-id to Disk...1683test # [ 6.905295] systemd[1]: Starting register-nix-paths.service...1684test # [ 6.908123] systemd[1]: Starting Create SUID/SGID Wrappers...1685test # [ 6.910397] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1686second # [ 6.956914] systemd[1]: Starting Create System Files and Directories...1687test # [ 6.922130] systemd[1]: Starting Save Transient machine-id to Disk...1688test # [ 6.930124] systemd[1]: Starting Create System Files and Directories...1689second # [ 7.006877] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1690second # [ 7.014769] systemd[1]: Finished Save Transient machine-id to Disk.1691test # [ 6.988306] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1692test # [ 6.993468] systemd[1]: Finished Save Transient machine-id to Disk.1693second # [ 7.060497] systemd[1]: Finished Create System Files and Directories.1694second # [ 7.067144] systemd[1]: Starting Rebuild Journal Catalog...1695second # [ 7.242306] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31696second # [ 7.074542] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1697second # [ 7.257362] mousedev: PS/2 mouse device common for all mice1698test # [ 7.066256] systemd[1]: Finished Create System Files and Directories.1699test # [ 7.075232] systemd[1]: Starting Rebuild Journal Catalog...1700test # [ 7.080167] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1701second # [ 7.305491] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1702second # [ 7.142587] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1703second # [ 7.319864] ACPI: button: Power Button [PWRF]1704test # [ 7.286215] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1705second # [ 7.161240] systemd[1]: Finished Rebuild Journal Catalog.1706test # [ 7.294317] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31707second # [ 7.166935] systemd[1]: Starting Update is Completed...1708test # [ 7.151779] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1709test # [ 7.157848] systemd[1]: Finished Firewall.1710test # [ 7.164352] systemd[1]: Reached target Preparation for Network.1711test # [ 7.165425] systemd[1]: Listening on Network Management Resolve Hook Socket.1712second # [ 7.205111] systemd[1]: Finished Firewall.1713second # [ 7.205939] systemd[1]: Reached target Preparation for Network.1714test # [ 7.173216] systemd[1]: Starting Network Management...1715test # [ 7.343309] mousedev: PS/2 mouse device common for all mice1716second # [ 7.211152] systemd[1]: Listening on Network Management Resolve Hook Socket.1717second # [ 7.216328] systemd[1]: Starting Network Management...1718test # [ 7.182041] systemd[1]: Finished Rebuild Journal Catalog.1719second # [ 7.221698] systemd[1]: Finished Update is Completed.1720test # [ 7.197070] systemd[1]: Starting Update is Completed...1721test # [ 7.262494] systemd[1]: Finished Update is Completed.1722second # [ 7.460939] rtc_cmos PNP0B00:00: RTC can wake from S41723second # [ 7.468839] parport_pc 00:02: reported by Plug and Play ACPI1724second # [ 7.354648] systemd-resolved[346]: Clock change detected. Flushing caches.1725test # [ 7.364864] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input41726second # [ 7.468927] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1727second # [ 7.486329] rtc_cmos PNP0B00:00: registered as rtc01728test # [ 7.387967] systemd-resolved[365]: Clock change detected. Flushing caches.1729second # [ 7.486959] rtc_cmos PNP0B00:00: setting system clock to 2026-10-15T20:03:44 UTC (1792094624)1730second # [ 7.487048] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1731second # [ 7.488850] systemd-journald[317]: Time jumped backwards, rotating.1732test # [ 7.435440] ACPI: button: Power Button [PWRF]1733test # [ 7.505349] Console: switching to colour dummy device 80x251734test # [ 7.522520] rtc_cmos PNP0B00:00: RTC can wake from S41735test # [ 7.555751] rtc_cmos PNP0B00:00: registered as rtc01736test # [ 7.555845] rtc_cmos PNP0B00:00: setting system clock to 2026-10-15T20:03:45 UTC (1792094625)1737test # [ 7.555940] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1738second # [ 7.497448] Console: switching to colour dummy device 80x251739second # [ 7.554723] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1740second # [ 7.592454] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input41741second # [ 7.511642] systemd[1]: Finished register-nix-paths.service.1742test # [ 7.483534] systemd[1]: Finished register-nix-paths.service.1743second # [ 7.524402] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1744second # [ 7.528499] systemd[1]: Finished Create SUID/SGID Wrappers.1745second # [ 7.529819] systemd[1]: Reached target System Initialization.1746test # [ 7.493801] systemd-networkd[523]: /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.1747second # [ 7.532655] systemd[1]: Started Discard unused filesystem blocks once a week.1748second # [ 7.534243] systemd[1]: Started Daily Cleanup of Temporary Directories.1749second # [ 7.536232] systemd[1]: Reached target Timer Units.1750test # [ 7.498264] systemd-networkd[523]: /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.1751test # [ 7.502331] systemd-networkd[523]: lo: Link UP1752second # [ 7.538648] systemd[1]: Listening on D-Bus System Message Bus Socket.1753test # [ 7.504747] systemd-networkd[523]: lo: Gained carrier1754second # [ 7.541136] systemd[1]: Listening on Nix Daemon Socket.1755test # [ 7.506074] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1756second # [ 7.542252] systemd-networkd[508]: /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.1757test # [ 7.510807] systemd[1]: Finished Create SUID/SGID Wrappers.1758test # [ 7.511781] systemd[1]: Started Network Management.1759test # [ 7.512701] systemd[1]: Reached target System Initialization.1760second # [ 7.546674] systemd-networkd[508]: /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.1761second # [ 7.549838] systemd-networkd[508]: lo: Link UP1762second # [ 7.552160] systemd-networkd[508]: lo: Gained carrier1763test # [ 7.517084] systemd-networkd[523]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1764test # [ 7.519462] systemd-networkd[523]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1765second # [ 7.555599] systemd[1]: Listening on Authorization Manager Agent Helper.1766second # [ 7.556725] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1767second # [ 7.558133] systemd[1]: Reached target Socket Units.1768test # [ 7.523087] systemd[1]: Started Discard unused filesystem blocks once a week.1769test # [ 7.525225] systemd[1]: Started Daily Cleanup of Temporary Directories.1770second # [ 7.560558] systemd-networkd[508]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1771test # [ 7.596048] cfg80211: Loading compiled-in X.509 certificates for regulatory database1772test # [ 7.617594] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1773second # [ 7.562922] systemd-networkd[508]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1774test # [ 7.528184] systemd[1]: Reached target Timer Units.1775test # [ 7.529133] systemd-networkd[523]: eth1: Link UP1776test # [ 7.530218] systemd-networkd[523]: eth1: Gained carrier1777second # [ 7.568307] systemd[1]: Starting D-Bus System Message Bus...1778test # [ 7.531161] systemd-networkd[523]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1779second # [ 7.569388] systemd[1]: Started Network Management.1780test # [ 7.533913] systemd[1]: Listening on D-Bus System Message Bus Socket.1781test # [ 7.535031] systemd[1]: Listening on Nix Daemon Socket.1782second # [ 7.571210] systemd-networkd[508]: eth1: Link UP1783second # [ 7.573350] systemd-networkd[508]: eth1: Gained carrier1784test # [ 7.538255] systemd[1]: Listening on Authorization Manager Agent Helper.1785test # [ 7.539386] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1786second # [ 7.574478] systemd-networkd[508]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1787test # [ 7.540784] systemd[1]: Reached target Socket Units.1788second # [ 7.578829] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1789test # [ 7.545096] systemd[1]: Starting D-Bus System Message Bus...1790test # [ 7.547713] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1791second # [ 7.633604] cfg80211: Loading compiled-in X.509 certificates for regulatory database1792second # [ 7.669260] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1793second # [ 7.669344] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1794test # [ 7.620326] parport_pc 00:02: reported by Plug and Play ACPI1795test # [ 7.636486] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1796test # [ 7.636569] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1797second # [ 7.697201] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1798second # [ 7.697302] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1799test # [ 7.591951] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1800second # [ 7.631429] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1801test # [ 7.655589] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1802test # [ 7.732661] NET: Registered PF_PACKET protocol family1803second # [ 7.768713] NET: Registered PF_PACKET protocol family1804second # [ 7.813946] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61805test # [ 7.777231] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1806second # [ 7.814757] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51807test # [ 7.777338] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1808second # [ 7.829234] [drm] Found bochs VGA, ID 0xb0c5.1809second # [ 7.829235] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1810test # [ 7.635282] dbus-broker-launch[570]: Looking up NSS user entry for 'systemd-timesync'...1811second # [ 7.842343] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1812second # [ 7.843159] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01813test # [ 7.808668] [drm] Found bochs VGA, ID 0xb0c5.1814test # [ 7.808670] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1815test # [ 7.642090] dbus-broker-launch[570]: NSS returned no entry for 'systemd-timesync'1816test # [ 7.643339] dbus-broker-launch[570]: Invalid user-name in /nix/store/45zfh3hgljlisqjrz0fg7jkdgp11y92r-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1817second # [ 7.681168] systemd[1]: Starting Virtual Console Setup...1818test # [ 7.814996] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1819test # [ 7.815806] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01820second # [ 7.682901] dbus-broker-launch[551]: Looking up NSS user entry for 'systemd-timesync'...1821second # [ 7.689583] dbus-broker-launch[551]: NSS returned no entry for 'systemd-timesync'1822second # [ 7.690907] dbus-broker-launch[551]: Invalid user-name in /nix/store/45zfh3hgljlisqjrz0fg7jkdgp11y92r-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1823test # [ 7.826432] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61824test # [ 7.827806] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51825second # [ 7.713400] dbus-broker-launch[551]: 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 # [ 7.682983] systemd[1]: Starting Virtual Console Setup...1827second # [ 7.717167] dbus-broker-launch[551]: 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 # [ 7.684287] 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'1829second # [ 7.722144] dbus-broker-launch[551]: 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 # [ 7.687719] 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'1831second # [ 7.725581] dbus-broker-launch[551]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/d527hwwggm2wz18p4v8cr39amcpx3g4c-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1832second # [ 7.729670] systemd[1]: Started D-Bus System Message Bus.1833second # [ 7.731179] systemd[1]: Reached target Basic System.1834test # [ 7.693102] 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'1835second # [ 7.736121] systemd[1]: Starting Network Manager Script Dispatcher Service...1836test # [ 7.703310] 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'1837second # [ 7.742139] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1838test # [ 7.708666] systemd[1]: Started D-Bus System Message Bus.1839test # [ 7.710657] systemd[1]: Reached target Basic System.1840second # [ 7.746920] systemd[1]: Starting Network Manager...1841test # [ 7.716929] systemd[1]: Starting Network Manager Script Dispatcher Service...1842second # [ 7.759096] systemd[1]: Started backdoor.service.1843test # [ 7.726402] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1844second # [ 7.765475] dbus-broker-launch[551]: Ready1845test # [ 7.729597] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1846second # [ 7.772882] systemd[1]: Starting Import lastlog data into lastlog2 database...1847test # [ 7.739075] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1848test # [ 7.741885] dbus-broker-launch[570]: Ready1849second # [ 7.781940] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1850second # [ 7.804878] systemd[1]: Starting Post-Boot Actions...1851second # [ 7.828138] systemd[1]: Started Reset console on configuration changes.1852second # [ 7.853606] 5y3ssc77g9a48j0jag4pj6wrwmh8mnj4-wifi-secrets[563]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1853second # connecting to host...1854second # [ 7.900637] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1855second # [ 7.913670] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1856second # [ 7.921627] nsncd[567]: Oct 15 20:03:45.102 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1857second # [ 7.930647] systemd[1]: Finished Post-Boot Actions.1858second # [ 7.932164] systemd[1]: Started Name Service Cache Daemon (nsncd).1859test # [ 7.966343] Console: switching to colour frame buffer device 160x501860second # [ 7.969936] systemd[1]: Finished Import lastlog data into lastlog2 database.1861second # [ 7.976536] systemd[1]: Started Network Manager Script Dispatcher Service.1862second # [ 7.980497] systemd[1]: Reached target Host and Network Name Lookups.1863second # [ 7.981600] systemd[1]: Reached target User and Group Name Lookups.1864second # [ 7.982747] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1865second # [ 7.992538] systemd[1]: Starting User Login Management...1866test # [ 8.130032] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1867test # [ 7.752975] systemd[1]: Starting Network Manager...1868test # [ 7.964358] systemd[1]: Started backdoor.service.1869test # [ 7.965393] 5y3ssc77g9a48j0jag4pj6wrwmh8mnj4-wifi-secrets[579]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1870test # [ 7.968947] systemd[1]: Starting Import lastlog data into lastlog2 database...1871test # connecting to host...1872test # [ 7.972329] 2fzr21av002hkddm9grsnsbb2wa3c1bn-wifi-secrets[580]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1873test # [ 7.976309] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1874test # [ 7.977532] bjn7ymirp1h8ai1aki9b9jfc6bwb1c5s-wifi-secrets[581]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1875test # [ 7.979841] systemd[1]: Starting Post-Boot Actions...1876test # [ 7.981698] systemd[1]: Started Reset console on configuration changes.1877test # [ 7.984318] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1878test # [ 7.987323] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1879test # [ 7.989579] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1880test # [ 7.994419] nsncd[586]: Oct 15 20:03:46.037 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1881test # [ 7.996918] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1882test # [ 8.001482] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1883test # [ 8.003087] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1884test # [ 8.004245] systemd[1]: Started Name Service Cache Daemon (nsncd).1885second # [ 8.126473] ppdev: user-space parallel port driver1886second # [ 8.159275] Console: switching to colour frame buffer device 160x501887second # [ 8.211205] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1888second # [ 8.017730] systemd[1]: Starting Hostname Service...1889test # [ 8.008091] systemd[1]: Finished Post-Boot Actions.1890test # [ 8.008953] systemd[1]: Started Network Manager Script Dispatcher Service.1891test # [ 8.010107] systemd[1]: Reached target Host and Network Name Lookups.1892test # [ 8.011175] systemd[1]: Reached target User and Group Name Lookups.1893test # [ 8.013832] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1894test # [ 8.016240] systemd[1]: Starting User Login Management...1895test # [ 8.018123] systemd[1]: Finished Import lastlog data into lastlog2 database.1896second # [ 8.054130] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1897test # [ 8.019381] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1898test # [ 8.022231] systemd[1]: Stopped Virtual Console Setup.1899second # [ 8.058743] systemd[1]: Stopped Virtual Console Setup.1900test: Guest shell says: b'Spawning backdoor root shell...\n'1901second # [ 8.065861] systemd[1]: Starting Virtual Console Setup...1902test: connected to guest root shell1903test: (connecting took 8.70 seconds)1904test: (finished: waiting for the VM to finish booting, in 8.70 seconds)1905second # [ 8.250215] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1906test # [ 8.055214] systemd[1]: Starting Virtual Console Setup...1907second # [ 8.090779] systemd-logind[604]: Watching system buttons on /dev/input/event2 (Power Button)1908second # [ 8.094619] systemd-logind[604]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1909second # [ 8.096218] systemd-logind[604]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1910second # [ 8.097809] systemd-logind[604]: New seat seat0.1911second # [ 8.101067] systemd[1]: Started User Login Management.1912second # [ 8.107234] systemd[1]: Starting linger-users.service...1913test # [ 8.080751] systemd[1]: Starting Hostname Service...1914second # [ 8.296498] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1915second # [ 8.152202] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1916second # [ 8.155045] systemd[1]: Stopped Virtual Console Setup.1917second # [ 8.160990] systemd[1]: linger-users.service: Deactivated successfully.1918test # [ 8.126442] systemd-logind[619]: Watching system buttons on /dev/input/event3 (Power Button)1919second # [ 8.163070] systemd[1]: Finished linger-users.service.1920second # [ 8.167744] systemd[1]: Started Hostname Service.1921test # [ 8.129548] systemd-logind[619]: Watching system buttons on /dev/input/event2 (QEMU Virtio Keyboard)1922test # [ 8.132885] systemd-logind[619]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1923test # [ 8.135213] systemd-logind[619]: New seat seat0.1924test # [ 8.138182] systemd[1]: Started User Login Management.1925test # [ 8.310972] ppdev: user-space parallel port driver1926second # [ 8.178811] systemd[1]: Starting Virtual Console Setup...1927test # [ 8.147705] systemd[1]: Starting linger-users.service...1928test # [ 8.325779] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1929second # [ 8.228954] systemd-networkd[508]: eth0: Link UP1930second # [ 8.232375] systemd-networkd[508]: eth0: Gained carrier1931test # [ 8.197604] systemd[1]: linger-users.service: Deactivated successfully.1932test # [ 8.202358] systemd[1]: Finished linger-users.service.1933test # [ 8.209970] systemd[1]: Started Hostname Service.1934second # [ 8.249948] systemd[1]: Started Network Manager.1935test # [ 8.385918] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1936second # [ 8.255552] systemd[1]: Reached target Network.1937test # [ 8.231549] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1938test # [ 8.234565] systemd[1]: Stopped Virtual Console Setup.1939test # [ 8.243470] systemd[1]: Starting Virtual Console Setup...1940second # [ 8.299667] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1941test # [ 8.276380] systemd-networkd[523]: eth0: Link UP1942second # [ 8.313160] systemd[1]: Starting Permit User Sessions...1943test # [ 8.278168] systemd-networkd[523]: eth0: Gained carrier1944test # [ 8.300507] systemd[1]: Started Network Manager.1945test # [ 8.301400] systemd[1]: Reached target Network.1946test # [ 8.310165] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1947test # [ 8.314178] systemd[1]: Starting Permit User Sessions...1948second # [ 8.521290] kvm_amd: TSC scaling supported1949second # [ 8.521802] kvm_amd: Nested Virtualization enabled1950second # [ 8.522352] kvm_amd: Nested Paging enabled1951second # [ 8.523309] kvm_amd: LBR virtualization supported1952second # [ 8.524165] kvm_amd: Virtual GIF supported1953second # [ 8.524888] kvm_amd: Virtual NMI enabled1954second # [ 8.364665] systemd[1]: Finished Permit User Sessions.1955second # [ 8.380399] systemd[1]: Started Getty on tty1.1956second # [ 8.383492] systemd[1]: Reached target Login Prompts.1957test # [ 8.365540] systemd[1]: Finished Permit User Sessions.1958second # [ 8.585934] EDAC MC: Ver: 3.0.01959test # [ 8.386522] systemd[1]: Started Getty on tty1.1960test # [ 8.387396] systemd[1]: Reached target Login Prompts.1961second # [ 8.426541] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1962second # [ 8.431112] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1963second # [ 8.436278] systemd[1]: Reached target Multi-User System.1964second # [ 8.464468] systemd-resolved[346]: eth0: Bus client set default route setting: yes1965second # [ 8.468124] systemd-resolved[346]: eth0: Bus client set DNS server list to: 10.0.2.31966test # [ 8.618115] kvm_amd: TSC scaling supported1967test # [ 8.618627] kvm_amd: Nested Virtualization enabled1968test # [ 8.619176] kvm_amd: Nested Paging enabled1969test # [ 8.454928] NetworkManager[582]: <warn> [1792094626.5663] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1970second # [ 8.492635] systemd-vconsole-setup[621]: Configuration of first virtual console was skipped, ignoring remaining ones.1971test # [ 8.627605] kvm_amd: LBR virtualization supported1972test # [ 8.628156] kvm_amd: Virtual GIF supported1973test # [ 8.630495] kvm_amd: Virtual NMI enabled1974second # [ 8.502073] systemd[1]: Finished Virtual Console Setup.1975second # [ 8.504592] systemd[1]: Startup finished in 773ms (kernel) + 4.281s (initrd) + 3.448s (userspace) = 8.503s.1976test # [ 8.481470] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1977test # [ 8.484974] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1978test # [ 8.495106] systemd[1]: Reached target Multi-User System.1979test # [ 8.678085] EDAC MC: Ver: 3.0.01980test # [ 8.540983] systemd-vconsole-setup[653]: Configuration of first virtual console was skipped, ignoring remaining ones.1981test # [ 8.544510] systemd[1]: Finished Virtual Console Setup.1982test # [ 8.548493] systemd[1]: Startup finished in 767ms (kernel) + 4.279s (initrd) + 3.500s (userspace) = 8.548s.1983test # [ 8.559247] systemd-resolved[365]: eth0: Bus client set default route setting: yes1984test # [ 8.562566] systemd-resolved[365]: eth0: Bus client set DNS server list to: 10.0.2.31985test # [ 9.034746] systemd-networkd[523]: eth1: Gained IPv6LL1986test: (finished: waiting for unit NetworkManager.service, in 9.82 seconds)1987test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1988test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.01 seconds)1989test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets1990test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets, in 0.01 seconds)1991test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection1992test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.01 seconds)1993(finished: run the VM test script, in 9.93 seconds)1994test script finished in 9.94s1995cleanup1996kill QemuMachine (pid 47)1997second # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/lb41b0anx1f98y9y5s9mdv97gjgsq740-python3-3.14.7/bin/python3.14)1998second # [2026-10-15T20:03:46Z INFO virtiofsd] Client disconnected, shutting down1999second # [2026-10-15T20:03:46Z INFO virtiofsd] Client disconnected, shutting down2000second # [2026-10-15T20:03:46Z INFO virtiofsd] Client disconnected, shutting down2001kill QemuMachine (pid 48)2002test # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/lb41b0anx1f98y9y5s9mdv97gjgsq740-python3-3.14.7/bin/python3.14)2003test # [2026-10-15T20:03:47Z INFO virtiofsd] Client disconnected, shutting down2004test # [2026-10-15T20:03:47Z INFO virtiofsd] Client disconnected, shutting down2005test # [2026-10-15T20:03:47Z INFO virtiofsd] Client disconnected, shutting down2006(finished: cleanup, in 0.21 seconds)