vm-test-run-wifi
checks.x86_64-linux.wifi
· build #189
· raw
1build-x86-01: building 8d7mpwcrgvkdgha4svnxhlr1s2rdvlb7-vm-test-run-wifi.drv2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7additionally exposed symbols:8 second, test,9 vlan1,10 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh11start all VMs12second: starting vm13test: starting vm14test # Disk image does not exist, creating the virtualisation disk image...15test # Formatting '/build/vm-state-test/tmp.nSHxmYAoG0', fmt=raw size=107374182416test # mke2fs 1.47.4 (6-Mar-2025)17test # Discarding device blocks: 0/262144 done18test # Creating filesystem with 262144 4k blocks and 65536 inodes19test # Filesystem UUID: d01fb6e5-30d5-460a-b0f0-876d1e7314a720test # Superblock backups stored on blocks:21test # 32768, 98304, 163840, 22937622test # 23test # Allocating group tables: 0/8 done24test # Writing inode tables: 0/8 done25test # Creating journal (8192 blocks): done26test # Writing superblocks and filesystem accounting information: 0/8 done27test # 28test # Virtualisation disk image created.29test # Starting virtiofs daemons...30test # [2026-09-27T20:10:34Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)31test # [2026-09-27T20:10:34Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether32test: QEMU running (pid 48)33test # [2026-09-27T20:10:34Z INFO virtiofsd] Waiting for vhost-user socket connection...34test # [2026-09-27T20:10:34Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)35test # [2026-09-27T20:10:34Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether36test # [2026-09-27T20:10:34Z INFO virtiofsd] Waiting for vhost-user socket connection...37test # [2026-09-27T20:10:34Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)38test # [2026-09-27T20:10:34Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether39test # [2026-09-27T20:10:34Z INFO virtiofsd] Waiting for vhost-user socket connection...40test # [2026-09-27T20:10:34Z INFO virtiofsd] Client connected, servicing requests41test # [2026-09-27T20:10:34Z INFO virtiofsd] Client connected, servicing requests42test # [2026-09-27T20:10:34Z INFO virtiofsd] Client connected, servicing requests43second # Disk image does not exist, creating the virtualisation disk image...44second: QEMU running (pid 47)45second # Formatting '/build/vm-state-second/tmp.KFRw9Dmn6e', fmt=raw size=107374182446second # mke2fs 1.47.4 (6-Mar-2025)47second # Discarding device blocks: 0/262144 done48second # Creating filesystem with 262144 4k blocks and 65536 inodes49second # Filesystem UUID: 57bfd2a7-f3f6-450a-a090-9dc8994d558e50second # Superblock backups stored on blocks:51second # 32768, 98304, 163840, 22937652second # 53second # Allocating group tables: 0/8 done54second # Writing inode tables: 0/8 done55second # Creating journal (8192 blocks): done56second # Writing superblocks and filesystem accounting information: 0/8 done57second # 58second # Virtualisation disk image created.59second # Starting virtiofs daemons...60second # [2026-09-27T20:10:34Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)61second # [2026-09-27T20:10:34Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether62second # [2026-09-27T20:10:34Z INFO virtiofsd] Waiting for vhost-user socket connection...63second # [2026-09-27T20:10:34Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)64second # [2026-09-27T20:10:34Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether65second # [2026-09-27T20:10:34Z INFO virtiofsd] Waiting for vhost-user socket connection...66second # [2026-09-27T20:10:34Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)67second # [2026-09-27T20:10:34Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether68second # [2026-09-27T20:10:34Z INFO virtiofsd] Waiting for vhost-user socket connection...69second # [2026-09-27T20:10:34Z INFO virtiofsd] Client connected, servicing requests70second # [2026-09-27T20:10:34Z INFO virtiofsd] Client connected, servicing requests71second # [2026-09-27T20:10:34Z INFO virtiofsd] Client connected, servicing requests72(finished: start all VMs, in 0.15 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 # 79second # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0080test # 81test # 82second # Press Ctrl-B to configure iPXE (PCI 00:02.0)...83second # 84second # 85second # 86second # 87test # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0088second # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0089test # Press Ctrl-B to configure iPXE (PCI 00:02.0)...90test # 91test # 92test # 93test # 94second # Press Ctrl-B to configure iPXE (PCI 00:05.0)...95second # 96second # 97test # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0098test # Press Ctrl-B to configure iPXE (PCI 00:05.0)...99test # 100test # 101second # Booting from ROM...102second # Probing EDD (edd=off to disable)... ok103test # Booting from ROM...104second # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 25 14:35:54 UTC 2026105second # [ 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/w79m1hl5gbjrvxc7wqb4l1lnjgkhgz23-nixos-system-second-test/init regInfo=/nix/.ro-store/15s93bvbbl42cdf0pl8fwk639pp97aw2-closure-info/registration console=ttyS0,115200n8 console=tty0106second # [ 0.000000] BIOS-provided physical RAM map:107second # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable108second # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved109second # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved110second # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable111second # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved112second # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved113second # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved114second # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved115second # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved116second # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved117second # [ 0.000000] NX (Execute Disable) protection: active118second # [ 0.000000] APIC: Static calls initialized119second # [ 0.000000] SMBIOS 2.8 present.120second # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014121second # [ 0.000000] DMI: Memory slots populated: 1/1122second # [ 0.000000] Hypervisor detected: KVM123second # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000124second # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00125second # [ 0.000000] kvm-clock: using sched offset of 423506138 cycles126second # [ 0.000003] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns127second # [ 0.000008] tsc: Detected 4192.120 MHz processor128second # [ 0.000607] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000129second # [ 0.000638] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs130test # Probing EDD (edd=off to disable)... o[ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 25 14:35:54 UTC 2026131second # [ 0.000641] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT132second # [ 0.002318] found SMP MP-table at [mem 0x000f5450-0x000f545f]133second # [ 0.002331] Using GB pages for direct mapping134second # [ 0.002430] RAMDISK: [mem 0x3e36d000-0x3ffcffff]135second # [ 0.002434] ACPI: Early table checksum verification disabled136test # [ 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/8f83hrdz2mq01n7jlxrjbsdxh73r414s-nixos-system-test-test/init regInfo=/nix/.ro-store/rq5nplragyhsmq1yyhx5l22m4gykil33-closure-info/registration console=ttyS0,115200n8 console=tty0137test # [ 0.000000] BIOS-provided physical RAM map:138second # [ 0.002438] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )139test # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable140second # [ 0.002441] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)141test # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved142second # [ 0.002445] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)143test # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved144second # [ 0.002450] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)145test # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable146second # [ 0.002452] ACPI: FACS 0x000000003FFE0000 000040147test # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved148second # [ 0.002453] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)149test # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved150second # [ 0.002454] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)151test # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved152test # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved153second # [ 0.002455] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)154test # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved155second # [ 0.002456] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)156test # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved157second # [ 0.002457] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]158test # [ 0.000000] NX (Execute Disable) protection: active159test # [ 0.000000] APIC: Static calls initialized160second # [ 0.002458] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]161test # [ 0.000000] SMBIOS 2.8 present.162second # [ 0.002459] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]163test # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014164second # [ 0.002459] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]165test # [ 0.000000] DMI: Memory slots populated: 1/1166test # [ 0.000000] Hypervisor detected: KVM167second # [ 0.002459] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]168test # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000169second # [ 0.002460] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]170test # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00171second # [ 0.002460] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]172test # [ 0.000000] kvm-clock: using sched offset of 457370093 cycles173second # [ 0.002817] No NUMA configuration found174second # [ 0.002818] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]175test # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns176second # [ 0.002821] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]177test # [ 0.000006] tsc: Detected 4192.120 MHz processor178second # [ 0.002892] Zone ranges:179test # [ 0.000603] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000180second # [ 0.002892] DMA [mem 0x0000000000001000-0x0000000000ffffff]181test # [ 0.000634] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs182second # [ 0.002894] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]183second # [ 0.002895] Normal empty184second # [ 0.002895] Device empty185test # [ 0.000636] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT186second # [ 0.002896] Movable zone start for each node187test # [ 0.002313] found SMP MP-table at [mem 0x000f5450-0x000f545f]188second # [ 0.002896] Early memory node ranges189test # [ 0.002331] Using GB pages for direct mapping190second # [ 0.002896] node 0: [mem 0x0000000000001000-0x000000000009efff]191test # [ 0.002426] RAMDISK: [mem 0x3e36c000-0x3ffcffff]192second # [ 0.002897] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]193test # [ 0.002431] ACPI: Early table checksum verification disabled194test # [ 0.002435] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )195second # [ 0.002898] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]196second # [ 0.002914] On node 0, zone DMA: 1 pages in unavailable ranges197test # [ 0.002439] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)198second # [ 0.003111] On node 0, zone DMA: 97 pages in unavailable ranges199test # [ 0.002444] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)200second # [ 0.016942] On node 0, zone DMA32: 40 pages in unavailable ranges201second # [ 0.017779] ACPI: PM-Timer IO Port: 0x608202test # [ 0.002450] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)203second # [ 0.017794] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])204test # [ 0.002452] ACPI: FACS 0x000000003FFE0000 000040205second # [ 0.017824] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23206test # [ 0.002453] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)207second # [ 0.017828] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)208test # [ 0.002454] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)209second # [ 0.017830] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)210second # [ 0.017832] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)211test # [ 0.002456] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)212second # [ 0.017834] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)213test # [ 0.002457] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)214second # [ 0.017836] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)215test # [ 0.002458] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]216second # [ 0.017841] ACPI: Using ACPI (MADT) for SMP configuration information217test # [ 0.002459] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]218second # [ 0.017842] ACPI: HPET id: 0x8086a201 base: 0xfed00000219second # [ 0.017848] TSC deadline timer available220test # [ 0.002459] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]221second # [ 0.017879] CPU topo: Max. logical packages: 1222test # [ 0.002459] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]223second # [ 0.017880] CPU topo: Max. logical dies: 1224second # [ 0.017881] CPU topo: Max. dies per package: 1225test # [ 0.002460] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]226second # [ 0.017911] CPU topo: Max. threads per core: 1227test # [ 0.002460] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]228second # [ 0.017912] CPU topo: Num. cores per package: 1229second # [ 0.017914] CPU topo: Num. threads per package: 1230test # [ 0.002461] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]231test # [ 0.002828] No NUMA configuration found232second # [ 0.017915] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs233test # [ 0.002829] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]234second # [ 0.017939] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()235test # [ 0.002831] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]236second # [ 0.017986] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]237test # [ 0.002900] Zone ranges:238second # [ 0.017988] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]239test # [ 0.002901] DMA [mem 0x0000000000001000-0x0000000000ffffff]240second # [ 0.017990] [mem 0x40000000-0xafffffff] available for PCI devices241test # [ 0.002902] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]242test # [ 0.002903] Normal empty243second # [ 0.017992] Booting paravirtualized kernel on KVM244test # [ 0.002904] Device empty245test # [ 0.002904] Movable zone start for each node246test # [ 0.002905] Early memory node ranges247second # [ 0.017998] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns248test # [ 0.002905] node 0: [mem 0x0000000000001000-0x000000000009efff]249second # [ 0.022169] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1250test # [ 0.002906] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]251second # [ 0.023990] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152252second # [ 0.024025] kvm-guest: PV spinlocks disabled, single CPU253test # [ 0.002907] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]254test # [ 0.002924] On node 0, zone DMA: 1 pages in unavailable ranges255test # [ 0.003119] On node 0, zone DMA: 97 pages in unavailable ranges256test # [ 0.016269] On node 0, zone DMA32: 40 pages in unavailable ranges257test # [ 0.017080] ACPI: PM-Timer IO Port: 0x608258test # [ 0.017093] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])259second # [ 0.024027] 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/w79m1hl5gbjrvxc7wqb4l1lnjgkhgz23-nixos-system-second-test/init regInfo=/nix/.ro-store/15s93bvbbl42cdf0pl8fwk639pp97aw2-closure-info/registration console=ttyS0,115200n8 console=tty0260test # [ 0.017114] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23261test # [ 0.017116] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)262second # [ 0.024103] Unknown kernel command line parameters "regInfo=/nix/.ro-store/15s93bvbbl42cdf0pl8fwk639pp97aw2-closure-info/registration", will be passed to user space.263test # [ 0.017118] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)264second # [ 0.024296] random: crng init done265test # [ 0.017119] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)266second # [ 0.024296] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes267test # [ 0.017120] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)268second # [ 0.025105] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)269test # [ 0.017120] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)270second # [ 0.025405] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)271test # [ 0.017122] ACPI: Using ACPI (MADT) for SMP configuration information272second # [ 0.025433] Fallback order for Node 0: 0273test # [ 0.017123] ACPI: HPET id: 0x8086a201 base: 0xfed00000274second # [ 0.025435] Built 1 zonelists, mobility grouping on. Total pages: 262006275test # [ 0.017126] TSC deadline timer available276second # [ 0.025436] Policy zone: DMA32277test # [ 0.017130] CPU topo: Max. logical packages: 1278second # [ 0.027068] mem auto-init: stack:all(zero), heap alloc:on, heap free:off279test # [ 0.017130] CPU topo: Max. logical dies: 1280test # [ 0.017131] CPU topo: Max. dies per package: 1281second # [ 0.029591] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1282test # [ 0.017134] CPU topo: Max. threads per core: 1283second # [ 0.031307] allocated 2097152 bytes of page_ext284test # [ 0.017134] CPU topo: Num. cores per package: 1285second # [ 0.041641] ftrace: allocating 48804 entries in 192 pages286test # [ 0.017134] CPU topo: Num. threads per package: 1287second # [ 0.041642] ftrace: allocated 192 pages with 2 groups288second # [ 0.042497] Dynamic Preempt: lazy289test # [ 0.017135] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs290second # [ 0.042640] rcu: Preemptible hierarchical RCU implementation.291test # [ 0.017149] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()292second # [ 0.042641] rcu: RCU event tracing is enabled.293test # [ 0.017177] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]294second # [ 0.042642] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.295second # [ 0.042643] Trampoline variant of Tasks RCU enabled.296test # [ 0.017178] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]297second # [ 0.042643] Rude variant of Tasks RCU enabled.298test # [ 0.017179] [mem 0x40000000-0xafffffff] available for PCI devices299second # [ 0.042643] Tracing variant of Tasks RCU enabled.300test # [ 0.017180] Booting paravirtualized kernel on KVM301second # [ 0.042644] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.302test # [ 0.017184] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns303second # [ 0.042645] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1304test # [ 0.020527] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1305second # [ 0.042664] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.306test # [ 0.022195] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152307second # [ 0.042665] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.308test # [ 0.022227] kvm-guest: PV spinlocks disabled, single CPU309second # [ 0.042666] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.310second # [ 0.046096] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16311second # [ 0.046356] rcu: srcu_init: Setting srcu_struct sizes based on contention.312second # [ 0.046363] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns313test # [ 0.022229] 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/8f83hrdz2mq01n7jlxrjbsdxh73r414s-nixos-system-test-test/init regInfo=/nix/.ro-store/rq5nplragyhsmq1yyhx5l22m4gykil33-closure-info/registration console=ttyS0,115200n8 console=tty0314second # [ 0.046526] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)315second # [ 0.051807] Console: colour VGA+ 80x25316test # [ 0.022302] Unknown kernel command line parameters "regInfo=/nix/.ro-store/rq5nplragyhsmq1yyhx5l22m4gykil33-closure-info/registration", will be passed to user space.317second # [ 0.051812] printk: legacy console [tty0] enabled318test # [ 0.022495] random: crng init done319second # [ 0.082968] printk: legacy console [ttyS0] enabled320test # [ 0.022496] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes321second # [ 0.221500] ACPI: Core revision 20250807322test # [ 0.023294] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)323test # [ 0.023306] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)324second # [ 0.222640] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns325test # [ 0.023336] Fallback order for Node 0: 0326second # [ 0.224778] APIC: Switch to symmetric I/O mode setup327test # [ 0.023338] Built 1 zonelists, mobility grouping on. Total pages: 262006328second # [ 0.226049] x2apic enabled329test # [ 0.023339] Policy zone: DMA32330second # [ 0.226920] APIC: Switched APIC routing to: physical x2apic331test # [ 0.025260] mem auto-init: stack:all(zero), heap alloc:on, heap free:off332test # [ 0.027871] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1333test # [ 0.029814] allocated 2097152 bytes of page_ext334second # [ 0.229000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1335test # [ 0.039993] ftrace: allocating 48804 entries in 192 pages336test # [ 0.039995] ftrace: allocated 192 pages with 2 groups337test # [ 0.041159] Dynamic Preempt: lazy338second # [ 0.230332] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3c6d4d0b6d3, max_idle_ns: 440795255641 ns339test # [ 0.041293] rcu: Preemptible hierarchical RCU implementation.340test # [ 0.041294] rcu: RCU event tracing is enabled.341second # [ 0.232585] Calibrating delay loop (skipped) preset value.. 8384.24 BogoMIPS (lpj=4192120)342test # [ 0.041295] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.343second # [ 0.234709] x86/cpu: User Mode Instruction Prevention (UMIP) activated344test # [ 0.041297] Trampoline variant of Tasks RCU enabled.345test # [ 0.041298] Rude variant of Tasks RCU enabled.346second # [ 0.235885] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127347test # [ 0.041298] Tracing variant of Tasks RCU enabled.348second # [ 0.236583] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0349test # [ 0.041299] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.350test # [ 0.041299] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1351second # [ 0.238587] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto352test # [ 0.041325] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.353second # [ 0.239583] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl354test # [ 0.041326] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.355second # [ 0.241583] Spectre V2 : Mitigation: Enhanced / Automatic IBRS356test # [ 0.041327] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.357second # [ 0.242583] Speculative Return Stack Overflow: Mitigation: Safe RET358test # [ 0.045023] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16359test # [ 0.045280] rcu: srcu_init: Setting srcu_struct sizes based on contention.360second # [ 0.243583] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization361test # [ 0.045288] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns362test # [ 0.045459] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)363test # [ 0.050799] Console: colour VGA+ 80x25364test # [ 0.050803] printk: legacy console [tty0] enabled365second # [ 0.244590] Spectre V2 : Enabling IBPB for BPF366test # [ 0.081732] printk: legacy console [ttyS0] enabled367test # [ 0.221758] ACPI: Core revision 20250807368second # [ 0.246584] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier369second # [ 0.247584] active return thunk: srso_alias_return_thunk370test # [ 0.222925] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns371second # [ 0.248612] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'372test # [ 0.225011] APIC: Switch to symmetric I/O mode setup373test # [ 0.226377] x2apic enabled374second # [ 0.249583] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'375second # [ 0.250583] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'376test # [ 0.227433] APIC: Switched APIC routing to: physical x2apic377second # [ 0.251583] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'378second # [ 0.253583] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'379test # [ 0.230014] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1380second # [ 0.254583] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'381test # [ 0.231430] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3c6d4d0b6d3, max_idle_ns: 440795255641 ns382second # [ 0.255583] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'383test # [ 0.233882] Calibrating delay loop (skipped) preset value.. 8384.24 BogoMIPS (lpj=4192120)384second # [ 0.256583] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'385test # [ 0.236879] x86/cpu: User Mode Instruction Prevention (UMIP) activated386second # [ 0.258583] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'387second # [ 0.259584] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256388test # [ 0.238360] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127389second # [ 0.260583] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64390test # [ 0.239877] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0391second # [ 0.261584] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512392test # [ 0.240883] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto393second # [ 0.263583] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024394second # [ 0.264583] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8395test # [ 0.241878] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl396test # [ 0.243878] Spectre V2 : Mitigation: Enhanced / Automatic IBRS397second # [ 0.265583] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16398test # [ 0.244878] Speculative Return Stack Overflow: Mitigation: Safe RET399second # [ 0.267583] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24400test # [ 0.246877] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization401second # [ 0.268583] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format.402test # [ 0.247884] Spectre V2 : Enabling IBPB for BPF403test # [ 0.249879] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier404test # [ 0.251879] active return thunk: srso_alias_return_thunk405test # [ 0.252909] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'406test # [ 0.254878] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'407test # [ 0.255878] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'408test # [ 0.257878] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'409test # [ 0.258877] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'410test # [ 0.259878] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'411test # [ 0.260878] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'412test # [ 0.261878] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'413test # [ 0.262877] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'414test # [ 0.263878] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256415test # [ 0.264877] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64416test # [ 0.265877] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512417test # [ 0.267879] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024418test # [ 0.268878] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8419test # [ 0.269877] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16420second # [ 0.299447] Freeing SMP alternatives memory: 44K421test # [ 0.270877] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24422second # [ 0.300406] pid_max: default: 32768 minimum: 301423test # [ 0.272877] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format.424second # [ 0.301711] LSM: initializing lsm=capability,landlock,yama,bpf,ima425second # [ 0.302725] landlock: Up and running.426second # [ 0.303583] Yama: becoming mindful.427second # [ 0.304841] LSM support for eBPF active428second # [ 0.305722] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)429second # [ 0.306605] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)430second # [ 0.309457] smpboot: CPU0: AMD Ryzen 9 7950X3D 16-Core Processor (family: 0x19, model: 0x61, stepping: 0x2)431second # [ 0.310321] Performance Events: Fam17h+ core perfctr, AMD PMU driver.432second # [ 0.310588] ... version: 2433second # [ 0.311538] ... bit width: 48434second # [ 0.312346] ... generic counters: 6435second # [ 0.312677] ... generic bitmap: 000000000000003f436second # [ 0.313585] ... fixed-purpose counters: 0437second # [ 0.314539] ... fixed-purpose bitmap: 0000000000000000438second # [ 0.315563] ... value mask: 0000ffffffffffff439second # [ 0.316560] ... max period: 00007fffffffffff440second # [ 0.317561] ... global_ctrl mask: 000000000000003f441second # [ 0.318656] signal: max sigframe size: 3376442second # [ 0.319775] rcu: Hierarchical SRCU implementation.443second # [ 0.320590] rcu: Max phase no-delay instances is 400.444second # [ 0.325115] smp: Bringing up secondary CPUs ...445second # [ 0.325608] smp: Brought up 1 node, 1 CPU446second # [ 0.326509] smpboot: Total of 1 processors activated (8384.24 BogoMIPS)447test # [ 0.305679] Freeing SMP alternatives memory: 44K448test # [ 0.306642] pid_max: default: 32768 minimum: 301449second # [ 0.327805] Memory: 943000K/1048024K available (17263K kernel code, 2728K rwdata, 13660K rodata, 3656K init, 2968K bss, 97572K reserved, 0K cma-reserved)450test # [ 0.307709] LSM: initializing lsm=capability,landlock,yama,bpf,ima451test # [ 0.309068] landlock: Up and running.452second # [ 0.328930] devtmpfs: initialized453test # [ 0.309878] Yama: becoming mindful.454second # [ 0.329814] x86/mm: Memory block size: 128MB455test # [ 0.311064] LSM support for eBPF active456test # [ 0.311973] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)457second # [ 0.331391] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)458test # [ 0.312907] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)459second # [ 0.332613] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).460second # [ 0.333669] pinctrl core: initialized pinctrl subsystem461test # [ 0.314993] smpboot: CPU0: AMD Ryzen 9 7950X3D 16-Core Processor (family: 0x19, model: 0x61, stepping: 0x2)462second # [ 0.334850] PM: RTC time: 20:10:34, date: 2026-09-27463test # [ 0.316330] Performance Events: Fam17h+ core perfctr, AMD PMU driver.464test # [ 0.316890] ... version: 2465test # [ 0.317806] ... bit width: 48466second # [ 0.337836] NET: Registered PF_NETLINK/PF_ROUTE protocol family467test # [ 0.318608] ... generic counters: 6468second # [ 0.338893] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations469test # [ 0.318880] ... generic bitmap: 000000000000003f470test # [ 0.319881] ... fixed-purpose counters: 0471second # [ 0.339607] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations472test # [ 0.320796] ... fixed-purpose bitmap: 0000000000000000473test # [ 0.321800] ... value mask: 0000ffffffffffff474second # [ 0.340695] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations475test # [ 0.322806] ... max period: 00007fffffffffff476second # [ 0.341599] audit: initializing netlink subsys (disabled)477test # [ 0.323815] ... global_ctrl mask: 000000000000003f478second # [ 0.342900] thermal_sys: Registered thermal governor 'fair_share'479test # [ 0.324898] signal: max sigframe size: 3376480second # [ 0.342902] thermal_sys: Registered thermal governor 'bang_bang'481test # [ 0.325952] rcu: Hierarchical SRCU implementation.482test # [ 0.326886] rcu: Max phase no-delay instances is 400.483second # [ 0.343589] audit: type=2000 audit(1790539835.336:1): state=initialized audit_enabled=0 res=1484second # [ 0.345587] thermal_sys: Registered thermal governor 'step_wise'485second # [ 0.345589] thermal_sys: Registered thermal governor 'user_space'486second # [ 0.346585] thermal_sys: Registered thermal governor 'power_allocator'487test # [ 0.331538] smp: Bringing up secondary CPUs ...488second # [ 0.347611] cpuidle: using governor menu489test # [ 0.331901] smp: Brought up 1 node, 1 CPU490test # [ 0.332813] smpboot: Total of 1 processors activated (8384.24 BogoMIPS)491second # [ 0.350431] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5492second # [ 0.351931] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]493test # [ 0.334129] Memory: 943040K/1048024K available (17263K kernel code, 2728K rwdata, 13660K rodata, 3656K init, 2968K bss, 97576K reserved, 0K cma-reserved)494test # [ 0.335406] devtmpfs: initialized495second # [ 0.352588] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry496test # [ 0.336127] x86/mm: Memory block size: 128MB497second # [ 0.353600] PCI: Using configuration type 1 for base access498second # [ 0.354863] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.499test # [ 0.337959] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)500test # [ 0.338903] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).501test # [ 0.340029] pinctrl core: initialized pinctrl subsystem502test # [ 0.341133] PM: RTC time: 20:10:34, date: 2026-09-27503second # [ 0.359796] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages504second # [ 0.360584] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page505test # [ 0.343965] NET: Registered PF_NETLINK/PF_ROUTE protocol family506test # [ 0.345199] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations507test # [ 0.345920] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations508second # [ 0.365584] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages509test # [ 0.347018] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations510second # [ 0.366585] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page511test # [ 0.347896] audit: initializing netlink subsys (disabled)512test # [ 0.349162] thermal_sys: Registered thermal governor 'fair_share'513test # [ 0.349164] thermal_sys: Registered thermal governor 'bang_bang'514test # [ 0.349880] thermal_sys: Registered thermal governor 'step_wise'515test # [ 0.350884] audit: type=2000 audit(1790539835.371:1): state=initialized audit_enabled=0 res=1516test # [ 0.352881] thermal_sys: Registered thermal governor 'user_space'517test # [ 0.352884] thermal_sys: Registered thermal governor 'power_allocator'518test # [ 0.353904] cpuidle: using governor menu519second # [ 0.376440] ACPI: Added _OSI(Module Device)520second # [ 0.377354] ACPI: Added _OSI(Processor Device)521test # [ 0.356672] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5522second # [ 0.377586] ACPI: Added _OSI(Processor Aggregator Device)523test # [ 0.358156] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]524test # [ 0.358882] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry525test # [ 0.359897] PCI: Using configuration type 1 for base access526second # [ 0.382509] ACPI: 1 ACPI AML tables successfully acquired and loaded527test # [ 0.361105] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.528second # [ 0.388620] ACPI: Interpreter enabled529test # [ 0.366139] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages530second # [ 0.389493] ACPI: PM: (supports S0 S3 S4 S5)531second # [ 0.390343] ACPI: Using IOAPIC for interrupt routing532test # [ 0.366879] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page533second # [ 0.392645] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug534second # [ 0.393585] PCI: Using E820 reservations for host bridge windows535test # [ 0.371879] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages536second # [ 0.394898] ACPI: Enabled 2 GPEs in block 00 to 3F537test # [ 0.372879] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page538second # [ 0.399086] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])539second # [ 0.399592] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]540second # [ 0.400649] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]541test # [ 0.381728] ACPI: Added _OSI(Module Device)542test # [ 0.382589] ACPI: Added _OSI(Processor Device)543second # [ 0.401690] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]544test # [ 0.382880] ACPI: Added _OSI(Processor Aggregator Device)545second # [ 0.403042] PCI host bridge to bus 0000:00546second # [ 0.403589] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]547second # [ 0.404586] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]548second # [ 0.405586] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]549test # [ 0.387845] ACPI: 1 ACPI AML tables successfully acquired and loaded550second # [ 0.406586] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]551second # [ 0.407586] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]552second # [ 0.408586] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]553second # [ 0.409587] pci_bus 0000:00: root bus resource [bus 00-ff]554test # [ 0.393881] ACPI: Interpreter enabled555test # [ 0.394750] ACPI: PM: (supports S0 S3 S4 S5)556second # [ 0.410699] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint557test # [ 0.395624] ACPI: Using IOAPIC for interrupt routing558second # [ 0.412271] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint559test # [ 0.397925] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug560test # [ 0.398880] PCI: Using E820 reservations for host bridge windows561test # [ 0.401521] ACPI: Enabled 2 GPEs in block 00 to 3F562second # [ 0.415652] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]563second # [ 0.416607] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]564second # [ 0.417627] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]565test # [ 0.406019] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])566second # [ 0.418763] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]567test # [ 0.406886] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]568second # [ 0.420472] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint569test # [ 0.407946] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]570test # [ 0.408983] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]571second # [ 0.423596] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]572test # [ 0.411032] PCI host bridge to bus 0000:00573second # [ 0.424598] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]574test # [ 0.411885] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]575second # [ 0.425625] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]576test # [ 0.412881] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]577second # [ 0.426597] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]578test # [ 0.413881] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]579test # [ 0.414881] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]580second # [ 0.428677] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint581test # [ 0.415880] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]582test # [ 0.416880] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]583second # [ 0.431451] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]584test # [ 0.417881] pci_bus 0000:00: root bus resource [bus 00-ff]585second # [ 0.432546] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]586second # [ 0.433626] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]587test # [ 0.419582] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint588test # [ 0.421684] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint589second # [ 0.435811] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint590second # [ 0.437596] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]591second # [ 0.438598] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]592second # [ 0.439624] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]593test # [ 0.425963] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]594test # [ 0.426905] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]595second # [ 0.441695] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint596test # [ 0.427927] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]597second # [ 0.444292] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]598test # [ 0.429247] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]599second # [ 0.444598] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]600test # [ 0.430762] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint601second # [ 0.445635] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]602second # [ 0.446597] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]603test # [ 0.433899] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]604second # [ 0.448632] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint605test # [ 0.434906] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]606test # [ 0.435917] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]607second # [ 0.450612] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]608test # [ 0.436889] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]609second # [ 0.451630] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]610test # [ 0.438857] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint611second # [ 0.453585] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint612test # [ 0.440892] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]613second # [ 0.455619] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]614test # [ 0.441899] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]615second # [ 0.456627] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]616test # [ 0.442923] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]617second # [ 0.458623] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint618test # [ 0.444991] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint619second # [ 0.460519] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]620second # [ 0.461594] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]621test # [ 0.447689] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]622test # [ 0.448765] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]623test # [ 0.449923] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]624second # [ 0.463536] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint625second # [ 0.465584] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]626test # [ 0.451947] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint627second # [ 0.466624] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]628test # [ 0.454892] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]629second # [ 0.468636] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint630test # [ 0.455891] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]631test # [ 0.456919] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]632second # [ 0.470605] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]633test # [ 0.457891] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]634second # [ 0.471595] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]635second # [ 0.472624] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]636test # [ 0.459937] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint637second # [ 0.474579] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint638test # [ 0.461900] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]639second # [ 0.476595] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]640test # [ 0.462919] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]641second # [ 0.477595] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]642test # [ 0.464828] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint643second # [ 0.478624] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]644second # [ 0.480773] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint645test # [ 0.466900] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]646test # [ 0.467919] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]647second # [ 0.482383] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]648test # [ 0.470029] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint649test # [ 0.471904] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]650test # [ 0.472919] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]651test # [ 0.475073] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint652second # [ 0.483745] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint653second # [ 0.486460] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]654test # [ 0.476909] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]655test # [ 0.477919] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]656second # [ 0.487780] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint657second # [ 0.489627] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]658test # [ 0.479835] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint659second # [ 0.490944] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint660second # [ 0.492264] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]661test # [ 0.483545] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]662test # [ 0.483893] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]663second # [ 0.492888] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint664test # [ 0.484921] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]665second # [ 0.494072] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO666test # [ 0.486828] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint667second # [ 0.494929] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint668second # [ 0.496634] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]669test # [ 0.489719] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]670second # [ 0.497602] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]671test # [ 0.490893] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]672second # [ 0.499073] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint673test # [ 0.491922] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]674second # [ 0.500492] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]675test # [ 0.493836] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint676test # [ 0.495733] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]677second # [ 0.506584] ACPI: PCI: Interrupt link LNKA configured for IRQ 10678second # [ 0.507725] ACPI: PCI: Interrupt link LNKB configured for IRQ 10679second # [ 0.508710] ACPI: PCI: Interrupt link LNKC configured for IRQ 11680second # [ 0.509710] ACPI: PCI: Interrupt link LNKD configured for IRQ 11681second # [ 0.510728] ACPI: PCI: Interrupt link LNKE configured for IRQ 10682second # [ 0.511716] ACPI: PCI: Interrupt link LNKF configured for IRQ 10683test # [ 0.497035] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint684second # [ 0.512717] ACPI: PCI: Interrupt link LNKG configured for IRQ 11685test # [ 0.499721] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]686second # [ 0.513709] ACPI: PCI: Interrupt link LNKH configured for IRQ 11687second # [ 0.514633] ACPI: PCI: Interrupt link GSIA configured for IRQ 16688test # [ 0.501285] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint689second # [ 0.515601] ACPI: PCI: Interrupt link GSIB configured for IRQ 17690test # [ 0.502702] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]691second # [ 0.516600] ACPI: PCI: Interrupt link GSIC configured for IRQ 18692second # [ 0.517600] ACPI: PCI: Interrupt link GSID configured for IRQ 19693test # [ 0.504128] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint694second # [ 0.518597] ACPI: PCI: Interrupt link GSIE configured for IRQ 20695second # [ 0.519597] ACPI: PCI: Interrupt link GSIF configured for IRQ 21696test # [ 0.505592] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]697second # [ 0.520598] ACPI: PCI: Interrupt link GSIG configured for IRQ 22698test # [ 0.506245] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint699second # [ 0.521597] ACPI: PCI: Interrupt link GSIH configured for IRQ 23700test # [ 0.507530] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO701second # [ 0.524160] iommu: Default domain type: Translated702second # [ 0.524592] iommu: DMA domain TLB invalidation policy: lazy mode703test # [ 0.508230] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint704second # [ 0.525814] ACPI: bus type USB registered705second # [ 0.526636] usbcore: registered new interface driver usbfs706test # [ 0.509935] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]707second # [ 0.527600] usbcore: registered new interface driver hub708test # [ 0.510890] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]709second # [ 0.528597] usbcore: registered new device driver usb710second # [ 0.530503] NetLabel: Initializing711test # [ 0.512482] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint712second # [ 0.531189] NetLabel: domain hash size = 128713second # [ 0.531584] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO714test # [ 0.513928] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]715second # [ 0.532625] NetLabel: unlabeled traffic allowed by default716second # [ 0.533598] PCI: Using ACPI for IRQ routing717test # [ 0.520179] ACPI: PCI: Interrupt link LNKA configured for IRQ 10718test # [ 0.521010] ACPI: PCI: Interrupt link LNKB configured for IRQ 10719test # [ 0.522016] ACPI: PCI: Interrupt link LNKC configured for IRQ 11720test # [ 0.523016] ACPI: PCI: Interrupt link LNKD configured for IRQ 11721test # [ 0.524017] ACPI: PCI: Interrupt link LNKE configured for IRQ 10722test # [ 0.525074] ACPI: PCI: Interrupt link LNKF configured for IRQ 10723test # [ 0.526085] ACPI: PCI: Interrupt link LNKG configured for IRQ 11724test # [ 0.527005] ACPI: PCI: Interrupt link LNKH configured for IRQ 11725test # [ 0.527929] ACPI: PCI: Interrupt link GSIA configured for IRQ 16726test # [ 0.528899] ACPI: PCI: Interrupt link GSIB configured for IRQ 17727test # [ 0.529896] ACPI: PCI: Interrupt link GSIC configured for IRQ 18728test # [ 0.530893] ACPI: PCI: Interrupt link GSID configured for IRQ 19729test # [ 0.531893] ACPI: PCI: Interrupt link GSIE configured for IRQ 20730test # [ 0.532897] ACPI: PCI: Interrupt link GSIF configured for IRQ 21731test # [ 0.533895] ACPI: PCI: Interrupt link GSIG configured for IRQ 22732test # [ 0.534902] ACPI: PCI: Interrupt link GSIH configured for IRQ 23733test # [ 0.537027] iommu: Default domain type: Translated734test # [ 0.537890] iommu: DMA domain TLB invalidation policy: lazy mode735test # [ 0.539131] ACPI: bus type USB registered736test # [ 0.539951] usbcore: registered new interface driver usbfs737test # [ 0.540910] usbcore: registered new interface driver hub738test # [ 0.541893] usbcore: registered new device driver usb739test # [ 0.543694] NetLabel: Initializing740test # [ 0.544485] NetLabel: domain hash size = 128741test # [ 0.544881] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO742test # [ 0.545958] NetLabel: unlabeled traffic allowed by default743test # [ 0.546894] PCI: Using ACPI for IRQ routing744second # [ 0.613144] pci 0000:00:01.0: vgaarb: setting as boot VGA device745second # [ 0.613581] pci 0000:00:01.0: vgaarb: bridge control possible746second # [ 0.613581] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none747second # [ 0.613592] vgaarb: loaded748second # [ 0.614518] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0749second # [ 0.615412] hpet0: 3 comparators, 64-bit 100.000000 MHz counter750second # [ 0.619671] clocksource: Switched to clocksource kvm-clock751second # [ 0.622932] VFS: Disk quotas dquot_6.6.0752second # [ 0.623818] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)753second # [ 0.625479] pnp: PnP ACPI init754second # [ 0.626417] ACPI: IRQ 4 override to edge(!), high(!)755second # [ 0.627600] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved756second # [ 0.629535] pnp: PnP ACPI: found 5 devices757second # [ 0.636793] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns758second # [ 0.638617] clocksource: Switched to clocksource acpi_pm759second # [ 0.639829] NET: Registered PF_INET protocol family760second # [ 0.641454] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)761test # [ 0.626499] pci 0000:00:01.0: vgaarb: setting as boot VGA device762test # [ 0.626875] pci 0000:00:01.0: vgaarb: bridge control possible763test # [ 0.626875] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none764test # [ 0.626885] vgaarb: loaded765test # [ 0.627746] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0766test # [ 0.628717] hpet0: 3 comparators, 64-bit 100.000000 MHz counter767second # [ 0.655096] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)768second # [ 0.657072] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)769test # [ 0.633012] clocksource: Switched to clocksource kvm-clock770second # [ 0.658739] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)771second # [ 0.660403] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)772second # [ 0.661974] TCP: Hash tables configured (established 8192 bind 8192)773test # [ 0.636564] VFS: Disk quotas dquot_6.6.0774test # [ 0.637456] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)775second # [ 0.663390] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)776test # [ 0.639106] pnp: PnP ACPI init777second # [ 0.664995] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)778test # [ 0.640166] ACPI: IRQ 4 override to edge(!), high(!)779second # [ 0.666424] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)780test # [ 0.641546] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved781second # [ 0.667968] NET: Registered PF_UNIX/PF_LOCAL protocol family782test # [ 0.643411] pnp: PnP ACPI: found 5 devices783second # [ 0.669171] NET: Registered PF_XDP protocol family784second # [ 0.670263] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]785second # [ 0.671538] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]786second # [ 0.672811] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]787second # [ 0.674260] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]788second # [ 0.675658] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]789test # [ 0.650628] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns790second # [ 0.677064] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]791test # [ 0.652416] clocksource: Switched to clocksource acpi_pm792second # [ 0.679116] ACPI: \_SB_.GSIA: Enabled at IRQ 16793test # [ 0.653666] NET: Registered PF_INET protocol family794second # [ 0.681169] ACPI: \_SB_.GSIB: Enabled at IRQ 17795test # [ 0.654922] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)796second # [ 0.683102] ACPI: \_SB_.GSIC: Enabled at IRQ 18797second # [ 0.685101] ACPI: \_SB_.GSID: Enabled at IRQ 19798second # [ 0.686872] PCI: CLS 0 bytes, default 64799second # [ 0.687951] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3c6d4d0b6d3, max_idle_ns: 440795255641 ns800second # [ 0.690365] Trying to unpack rootfs image as initramfs...801test # [ 0.669873] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)802test # [ 0.671750] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)803test # [ 0.673405] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)804test # [ 0.675066] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)805test # [ 0.676584] TCP: Hash tables configured (established 8192 bind 8192)806test # [ 0.678040] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)807test # [ 0.679639] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)808test # [ 0.681030] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)809test # [ 0.682544] NET: Registered PF_UNIX/PF_LOCAL protocol family810test # [ 0.683805] NET: Registered PF_XDP protocol family811test # [ 0.684905] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]812test # [ 0.686192] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]813test # [ 0.687461] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]814test # [ 0.688883] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]815test # [ 0.690275] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]816test # [ 0.691661] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]817test # [ 0.693761] ACPI: \_SB_.GSIA: Enabled at IRQ 16818test # [ 0.695932] ACPI: \_SB_.GSIB: Enabled at IRQ 17819test # [ 0.697972] ACPI: \_SB_.GSIC: Enabled at IRQ 18820test # [ 0.699963] ACPI: \_SB_.GSID: Enabled at IRQ 19821second # [ 0.726592] Initialise system trusted keyrings822test # [ 0.701795] PCI: CLS 0 bytes, default 64823test # [ 0.702874] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3c6d4d0b6d3, max_idle_ns: 440795255641 ns824second # [ 0.730301] workingset: timestamp_bits=40 max_order=18 bucket_order=0825test # [ 0.705012] Trying to unpack rootfs image as initramfs...826second # [ 0.749945] Key type asymmetric registered827second # [ 0.750989] Asymmetric key parser 'x509' registered828second # [ 0.754317] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)829second # [ 0.758311] io scheduler mq-deadline registered830second # [ 0.761258] io scheduler kyber registered831second # [ 0.762712] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled832second # [ 0.764602] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A833test # [ 0.743143] Initialise system trusted keyrings834second # [ 0.772294] Linux agpgart interface v0.103835test # [ 0.746674] workingset: timestamp_bits=40 max_order=18 bucket_order=0836second # [ 0.775327] ACPI: bus type drm_connector registered837second # [ 0.776835] usbcore: registered new interface driver usbserial_generic838second # [ 0.778277] usbserial: USB Serial support registered for generic839second # [ 0.782273] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.840second # [ 0.782273] Please enable it if your BIOS has the CPPC option.841second # [ 0.785434] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled842test # [ 0.762009] Key type asymmetric registered843test # [ 0.766631] Asymmetric key parser 'x509' registered844second # [ 0.792433] drop_monitor: Initializing network drop monitor service845second # [ 0.793978] NET: Registered PF_INET6 protocol family846test # [ 0.767808] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)847test # [ 0.771743] io scheduler mq-deadline registered848second # [ 0.797948] Segment Routing with IPv6849test # [ 0.774629] io scheduler kyber registered850second # [ 0.801266] In-situ OAM (IOAM) with IPv6851second # [ 0.802532] IPI shorthand broadcast: enabled852test # [ 0.777141] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled853test # [ 0.780784] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A854second # [ 0.810540] sched_clock: Marking stable (629022345, 181218001)->(911178955, -100938609)855test # [ 0.786635] Linux agpgart interface v0.103856test # [ 0.787556] ACPI: bus type drm_connector registered857second # [ 0.816412] registered taskstats version 1858second # [ 0.817486] Loading compiled-in X.509 certificates859test # [ 0.792218] usbcore: registered new interface driver usbserial_generic860test # [ 0.793582] usbserial: USB Serial support registered for generic861test # [ 0.796642] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.862test # [ 0.796642] Please enable it if your BIOS has the CPPC option.863test # [ 0.799364] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled864test # [ 0.803753] drop_monitor: Initializing network drop monitor service865test # [ 0.805154] NET: Registered PF_INET6 protocol family866second # [ 0.832389] Demotion targets for Node 0: null867second # [ 0.833481] Key type .fscrypt registered868test # [ 0.809079] Segment Routing with IPv6869second # [ 0.836269] Key type fscrypt-provisioning registered870second # [ 0.837448] ima: No TPM chip found, activating TPM-bypass!871test # [ 0.812656] In-situ OAM (IOAM) with IPv6872test # [ 0.813842] IPI shorthand broadcast: enabled873second # [ 0.840267] ima: Allocated hash algorithm: sha1874second # [ 0.841332] ima: No architecture policies found875second # [ 0.844414] PM: Magic number: 10:786:195876test # [ 0.819486] sched_clock: Marking stable (635021010, 183588558)->(914398926, -95789358)877second # [ 0.846096] RAS: Correctable Errors collector initialized.878test # [ 0.825894] registered taskstats version 1879test # [ 0.826979] Loading compiled-in X.509 certificates880second # [ 0.856297] clk: Disabling unused clocks881second # [ 0.857172] PM: genpd: Disabling unused power domains882test # [ 0.842759] Demotion targets for Node 0: null883test # [ 0.843852] Key type .fscrypt registered884test # [ 0.846631] Key type fscrypt-provisioning registered885test # [ 0.847788] ima: No TPM chip found, activating TPM-bypass!886test # [ 0.850629] ima: Allocated hash algorithm: sha1887test # [ 0.851679] ima: No architecture policies found888test # [ 0.854933] PM: Magic number: 10:786:195889test # [ 0.858821] RAS: Correctable Errors collector initialized.890test # [ 0.866969] clk: Disabling unused clocks891test # [ 0.869642] PM: genpd: Disabling unused power domains892second # [ 0.997086] Freeing initrd memory: 29068K893second # [ 1.000192] Freeing unused decrypted memory: 2028K894second # [ 1.003413] Freeing unused kernel image (initmem) memory: 3656K895second # [ 1.004881] Write protecting the kernel read-only data: 32768k896second # [ 1.006865] Freeing unused kernel image (text/rodata gap) memory: 1168K897second # [ 1.008582] Freeing unused kernel image (rodata/data gap) memory: 676K898test # [ 1.015090] Freeing initrd memory: 29072K899second # [ 1.042717] x86/mm: Checked W+X mappings: passed, no W+X pages found.900second # [ 1.044120] Run /init as init process901test # [ 1.018240] Freeing unused decrypted memory: 2028K902test # [ 1.021140] Freeing unused kernel image (initmem) memory: 3656K903test # [ 1.022641] Write protecting the kernel read-only data: 32768k904test # [ 1.024702] Freeing unused kernel image (text/rodata gap) memory: 1168K905test # [ 1.026508] Freeing unused kernel image (rodata/data gap) memory: 676K906second # [ 1.054411] systemd[1]: Inserted module 'autofs4'907second # [ 1.070270] fuse: init (API version 7.45)908second # [ 1.076661] ACPI: \_SB_.GSIG: Enabled at IRQ 22909second # [ 1.079501] ACPI: \_SB_.GSIH: Enabled at IRQ 23910second # [ 1.082915] ACPI: \_SB_.GSIE: Enabled at IRQ 20911second # [ 1.085523] ACPI: \_SB_.GSIF: Enabled at IRQ 21912test # [ 1.059882] x86/mm: Checked W+X mappings: passed, no W+X pages found.913test # [ 1.061243] Run /init as init process914second # [ 1.091109] virtiofs virtio5: discovered new tag: nix-store915second # [ 1.093437] virtiofs virtio5: virtio_fs_setup_dax: No cache capability916test # [ 1.071445] systemd[1]: Inserted module 'autofs4'917second # [ 1.101061] virtiofs virtio6: discovered new tag: shared918second # [ 1.103281] virtiofs virtio6: virtio_fs_setup_dax: No cache capability919second # [ 1.107262] virtiofs virtio7: discovered new tag: xchg920second # [ 1.109298] virtiofs virtio7: virtio_fs_setup_dax: No cache capability921test # [ 1.088551] fuse: init (API version 7.45)922test # [ 1.095129] ACPI: \_SB_.GSIG: Enabled at IRQ 22923test # [ 1.097926] ACPI: \_SB_.GSIH: Enabled at IRQ 23924test # [ 1.101282] ACPI: \_SB_.GSIE: Enabled at IRQ 20925test # [ 1.103562] ACPI: \_SB_.GSIF: Enabled at IRQ 21926second # [ 1.129873] systemd[1]: Successfully made /usr/ read-only.927test # [ 1.109333] virtiofs virtio5: discovered new tag: nix-store928test # [ 1.111408] virtiofs virtio5: virtio_fs_setup_dax: No cache capability929test # [ 1.119948] virtiofs virtio6: discovered new tag: shared930test # [ 1.121966] virtiofs virtio6: virtio_fs_setup_dax: No cache capability931test # [ 1.126187] virtiofs virtio7: discovered new tag: xchg932test # [ 1.128256] virtiofs virtio7: virtio_fs_setup_dax: No cache capability933test # [ 1.149140] systemd[1]: Successfully made /usr/ read-only.934second # [ 1.466036] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)935second # [ 1.472068] systemd[1]: Detected virtualization kvm.936second # [ 1.473159] systemd[1]: Detected architecture x86-64.937second # [ 1.474275] systemd[1]: Running in initrd.938second # [ 1.475483] systemd[1]: Initializing machine ID from random generator.939second # [ 1.477059] systemd[1]: Hostname set to <second>.940test # [ 1.485692] systemd[1]: systemd 261.2 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)941test # [ 1.491817] systemd[1]: Detected virtualization kvm.942test # [ 1.493049] systemd[1]: Detected architecture x86-64.943test # [ 1.494244] systemd[1]: Running in initrd.944test # [ 1.495542] systemd[1]: Initializing machine ID from random generator.945test # [ 1.497293] systemd[1]: Hostname set to <test>.946second # [ 1.661082] systemd[1]: bpf-restrict-fs: LSM BPF program attached947second # [ 1.693141] systemd[1]: Queued start job for default target Initrd Default Target.948test # [ 1.670279] systemd[1]: bpf-restrict-fs: LSM BPF program attached949second # [ 1.697457] systemd[1]: Created slice Slice /system/modprobe.950second # [ 1.698946] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.951second # [ 1.700707] systemd[1]: Expecting device /dev/disk/by-label/nixos...952second # [ 1.702123] systemd[1]: Reached target Path Units.953second # [ 1.703289] systemd[1]: Reached target Slice Units.954second # [ 1.704436] systemd[1]: Reached target Swaps.955second # [ 1.705487] systemd[1]: Reached target Timer Units.956second # [ 1.706751] systemd[1]: Listening on D-Bus System Message Bus Socket.957second # [ 1.708332] systemd[1]: Listening on Journal Socket (/dev/log).958second # [ 1.709795] systemd[1]: Listening on Journal Sockets.959second # [ 1.711065] systemd[1]: Listening on udev Control Socket.960second # [ 1.720886] systemd[1]: Listening on udev Kernel Socket.961second # [ 1.722118] systemd[1]: Reached target Socket Units.962second # [ 1.724307] systemd[1]: Starting Create List of Static Device Nodes...963test # [ 1.701677] systemd[1]: Queued start job for default target Initrd Default Target.964second # [ 1.729136] systemd[1]: Starting Load Kernel Module configfs...965test # [ 1.705870] systemd[1]: Created slice Slice /system/modprobe.966test # [ 1.707362] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.967test # [ 1.709148] systemd[1]: Expecting device /dev/disk/by-label/nixos...968test # [ 1.710616] systemd[1]: Reached target Path Units.969second # [ 1.737304] systemd[1]: Starting Journal Service...970test # [ 1.711757] systemd[1]: Reached target Slice Units.971test # [ 1.712943] systemd[1]: Reached target Swaps.972test # [ 1.714008] systemd[1]: Reached target Timer Units.973test # [ 1.715263] systemd[1]: Listening on D-Bus System Message Bus Socket.974test # [ 1.716795] systemd[1]: Listening on Journal Socket (/dev/log).975test # [ 1.718299] systemd[1]: Listening on Journal Sockets.976second # [ 1.744602] systemd[1]: Starting Load Kernel Modules...977second # [ 1.747368] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os978second # [ 1.754030] systemd[1]: Starting Coldplug All udev Devices...979test # [ 1.719686] systemd[1]: Listening on udev Control Socket.980test # [ 1.730687] systemd[1]: Listening on udev Kernel Socket.981test # [ 1.731929] systemd[1]: Reached target Socket Units.982test # [ 1.734069] systemd[1]: Starting Create List of Static Device Nodes...983test # [ 1.740715] systemd[1]: Starting Load Kernel Module configfs...984second # [ 1.769518] systemd[1]: Finished Create List of Static Device Nodes.985second # [ 1.775881] systemd[1]: modprobe@configfs.service: Deactivated successfully.986test # [ 1.750831] systemd[1]: Starting Journal Service...987second # [ 1.779067] systemd-journald[65]: Collecting audit messages is disabled.988second # [ 1.784769] systemd[1]: Finished Load Kernel Module configfs.989test # [ 1.759160] systemd[1]: Starting Load Kernel Modules...990test # [ 1.762710] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os991second # [ 1.791843] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config992second # [ 1.795537] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.993test # [ 1.771685] systemd[1]: Starting Coldplug All udev Devices...994second # [ 1.804418] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...995second # [ 1.807357] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev996test # [ 1.785761] systemd[1]: Finished Create List of Static Device Nodes.997test # [ 1.788029] systemd-journald[65]: Collecting audit messages is disabled.998test # [ 1.794259] systemd[1]: modprobe@configfs.service: Deactivated successfully.999test # [ 1.802150] systemd[1]: Finished Load Kernel Module configfs.1000test # [ 1.808091] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config1001second # [ 1.838893] systemd[1]: Finished Load Kernel Modules.1002test # [ 1.813073] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1003second # [ 1.845400] systemd[1]: Starting Apply Kernel Variables...1004test # [ 1.819265] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1005test # [ 1.822649] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1006second # [ 1.860413] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1007second # [ 1.870465] systemd[1]: Starting Create Static Device Nodes in /dev...1008test # [ 1.857143] systemd[1]: Finished Load Kernel Modules.1009second # [ 1.885471] systemd[1]: Finished Apply Kernel Variables.1010test # [ 1.863757] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1011test # [ 1.873040] systemd[1]: Starting Apply Kernel Variables...1012second # [ 1.906039] systemd[1]: Finished Create Static Device Nodes in /dev.1013test # [ 1.885688] systemd[1]: Starting Create Static Device Nodes in /dev...1014second # [ 1.911590] systemd[1]: Reached target Preparation for Local File Systems.1015second # [ 1.915383] systemd[1]: Reached target Local File Systems.1016second # [ 1.923453] systemd[1]: Starting Rule-based Manager for Device Events and Files...1017second # [ 1.744331] systemd-modules-load[66]: Inserted module 'dm_mod'1018second # [ 1.747500] systemd-modules-load[66]: Inserted module 'virtio_balloon'1019second # [ 1.749427] systemd-modules-load[66]: Inserted module 'virtio_gpu'1020second # [ 1.938520] systemd[1]: Started Journal Service.1021test # [ 1.918379] systemd[1]: Finished Apply Kernel Variables.1022test # [ 1.924710] systemd[1]: Finished Create Static Device Nodes in /dev.1023second # [ 1.774276] systemd[1]: Starting Create System Files and Directories...1024test # [ 1.930948] systemd[1]: Reached target Preparation for Local File Systems.1025test # [ 1.749604] systemd-modules-load[66]: Inserted module 'dm_mod'[ 1.935698] systemd[1]: Reached target Local File Systems.1026test # 1027test # [ 1.753429] systemd-modules-load[66]: Inserted module 'virtio_balloon'1028test # [ 1.754797] systemd-modules-load[66]: Inserted module 'virtio_gpu'1029test # [ 1.943811] systemd[1]: Starting Rule-based Manager for Device Events and Files...1030test # [ 1.950809] systemd[1]: Started Journal Service.1031second # [ 1.797273] systemd[1]: Finished Create System Files and Directories.1032second # [ 1.805236] systemd-udevd[73]: Using default interface naming scheme 'v261'.1033test # [ 1.785381] systemd[1]: Starting Create System Files and Directories...1034second # [ 1.827388] systemd[1]: Started Rule-based Manager for Device Events and Files.1035test # [ 1.810312] systemd-udevd[73]: Using default interface naming scheme 'v261'.1036second # [ 1.839790] systemd[1]: Finished Coldplug All udev Devices.1037test # [ 1.812134] systemd[1]: Finished Create System Files and Directories.1038second # [ 1.840959] systemd[1]: Reached target System Initialization.1039second # [ 1.842104] systemd[1]: Reached target Basic System.1040test # [ 1.834849] systemd[1]: Started Rule-based Manager for Device Events and Files.1041test # [ 1.842808] systemd[1]: Finished Coldplug All udev Devices.1042test # [ 1.843896] systemd[1]: Reached target System Initialization.1043test # [ 1.845061] systemd[1]: Reached target Basic System.1044second # [ 2.233641] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121045second # [ 2.245641] serio: i8042 KBD port at 0x60,0x64 irq 11046second # [ 2.246583] serio: i8042 AUX port at 0x60,0x64 irq 121047second # [ 2.248668] virtio_blk virtio2: 1/0/0 default/read/poll queues1048second # [ 2.255116] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1049test # [ 2.231529] virtio_blk virtio2: 1/0/0 default/read/poll queues1050second # [ 2.266319] ehci-pci 0000:00:1d.7: EHCI Host Controller1051second # [ 2.267181] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11052test # [ 2.242497] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121053second # [ 2.269094] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001054second # [ 2.276361] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001055test # [ 2.250944] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1056second # [ 2.277443] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181057second # [ 2.279310] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11058test # [ 2.255393] serio: i8042 KBD port at 0x60,0x64 irq 11059second # [ 2.281995] usb usb1: Product: EHCI Host Controller1060second # [ 2.283017] usb usb1: Manufacturer: Linux 6.18.54 ehci_hcd1061second # [ 2.285273] usb usb1: SerialNumber: 0000:00:1d.71062second # [ 2.286466] hub 1-0:1.0: USB hub found1063test # [ 2.261612] serio: i8042 AUX port at 0x60,0x64 irq 121064second # [ 2.288243] hub 1-0:1.0: 6 ports detected1065second # [ 2.294834] uhci_hcd 0000:00:1d.0: UHCI Host Controller1066second # [ 2.295725] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21067test # [ 2.270376] ehci-pci 0000:00:1d.7: EHCI Host Controller1068test # [ 2.271289] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11069test # [ 2.273422] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001070second # [ 2.306266] uhci_hcd 0000:00:1d.0: detected 2 ports1071test # [ 2.281648] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001072test # [ 2.282797] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181073test # [ 2.285537] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11074second # [ 2.313805] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801075test # [ 2.289616] usb usb1: Product: EHCI Host Controller1076test # [ 2.290476] usb usb1: Manufacturer: Linux 6.18.54 ehci_hcd1077test # [ 2.291669] usb usb1: SerialNumber: 0000:00:1d.71078test # [ 2.294152] hub 1-0:1.0: USB hub found1079test # [ 2.295875] hub 1-0:1.0: 6 ports detected1080second # [ 2.321601] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181081second # [ 2.322941] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11082test # [ 2.298125] uhci_hcd 0000:00:1d.0: UHCI Host Controller1083test # [ 2.299043] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21084second # [ 2.327200] SCSI subsystem initialized1085test # [ 2.311668] uhci_hcd 0000:00:1d.0: detected 2 ports1086second # [ 2.339810] usb usb2: Product: UHCI Host Controller1087test # [ 2.319411] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801088second # [ 2.349535] usb usb2: Manufacturer: Linux 6.18.54 uhci_hcd1089test # [ 2.326471] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181090second # [ 2.172188] systemd[1]: Starting Virtual Console Setup...1091test # [ 2.328801] SCSI subsystem initialized1092second # [ 2.174916] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line.1093test # [ 2.333231] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11094second # [ 2.362655] usb usb2: SerialNumber: 0000:00:1d.01095second # [ 2.366433] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01096test # [ 2.341864] usb usb2: Product: UHCI Host Controller1097test # [ 2.349408] usb usb2: Manufacturer: Linux 6.18.54 uhci_hcd1098test # [ 2.350615] usb usb2: SerialNumber: 0000:00:1d.01099second # [ 2.196717] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line.1100test # [ 2.173617] systemd[1]: Starting Virtual Console Setup...1101second # [ 2.206314] systemd-vconsole-setup[97]: Configuration of first virtual console was skipped, ignoring remaining ones.1102second # [ 2.389757] hub 2-0:1.0: USB hub found1103second # [ 2.209364] systemd[1]: Finished Virtual Console Setup.1104second # [ 2.393720] hub 2-0:1.0: 2 ports detected1105test # [ 2.369759] hub 2-0:1.0: USB hub found1106test # [ 2.191247] (udev-worker)[85]: Network interface NamePolicy= disabled on kernel command line.1107second # [ 2.404088] uhci_hcd 0000:00:1d.1: UHCI Host Controller1108test # [ 2.199167] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line.1109second # [ 2.413242] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31110test # [ 2.388936] hub 2-0:1.0: 2 ports detected1111test # [ 2.207653] systemd-vconsole-setup[96]: Configuration of first virtual console was skipped, ignoring remaining ones.1112second # [ 2.418348] uhci_hcd 0000:00:1d.1: detected 2 ports1113second # [ 2.239359] systemd[1]: Found device /dev/disk/by-label/nixos.1114test # [ 2.211358] systemd[1]: Finished Virtual Console Setup.1115second # [ 2.240503] systemd[1]: Reached target Initrd Root Device.1116second # [ 2.244923] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1117test # [ 2.403147] uhci_hcd 0000:00:1d.1: UHCI Host Controller1118second # [ 2.429312] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01119test # [ 2.404055] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31120second # [ 2.430842] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181121second # [ 2.438296] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11122test # [ 2.412438] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01123second # [ 2.441177] usb usb3: Product: UHCI Host Controller1124second # [ 2.444157] usb usb3: Manufacturer: Linux 6.18.54 uhci_hcd1125second # [ 2.445738] usb usb3: SerialNumber: 0000:00:1d.11126second # [ 2.447918] hub 3-0:1.0: USB hub found1127test # [ 2.241263] systemd[1]: Found device /dev/disk/by-label/nixos.1128test # [ 2.242708] systemd[1]: Reached target Initrd Root Device.1129second # [ 2.273496] systemd-fsck[105]: nixos: clean, 12/65536 files, 13019/262144 blocks1130second # [ 2.456735] hub 3-0:1.0: 2 ports detected1131test # [ 2.246735] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1132second # [ 2.459322] uhci_hcd 0000:00:1d.2: UHCI Host Controller1133test # [ 2.434045] uhci_hcd 0000:00:1d.1: detected 2 ports1134second # [ 2.460197] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41135second # [ 2.465317] uhci_hcd 0000:00:1d.2: detected 2 ports1136test # [ 2.440235] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01137second # [ 2.467293] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01138second # [ 2.469172] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1139second # [ 2.471012] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181140second # [ 2.472384] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11141test # [ 2.446814] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181142second # [ 2.473886] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1143second # [ 2.475290] ahci 0000:00:1f.2: flags: 64bit ncq only1144test # [ 2.452711] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11145second # [ 2.297920] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1146test # [ 2.454191] usb usb3: Product: UHCI Host Controller1147second # [ 2.481054] usb usb4: Product: UHCI Host Controller1148second # [ 2.482345] usb usb4: Manufacturer: Linux 6.18.54 uhci_hcd1149test # [ 2.456666] usb usb3: Manufacturer: Linux 6.18.54 uhci_hcd1150test # [ 2.457566] usb usb3: SerialNumber: 0000:00:1d.11151second # [ 2.486280] usb usb4: SerialNumber: 0000:00:1d.21152second # [ 2.488373] hub 4-0:1.0: USB hub found1153second # [ 2.489601] scsi host0: ahci1154second # [ 2.491958] scsi host1: ahci1155test # [ 2.281890] systemd-fsck[104]: nixos: clean, 12/65536 files, 13019/262144 blocks1156second # [ 2.493352] hub 4-0:1.0: 2 ports detected1157test # [ 2.467614] hub 3-0:1.0: USB hub found1158second # [ 2.496278] scsi host2: ahci1159test # [ 2.288669] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1160test # [ 2.473548] hub 3-0:1.0: 2 ports detected1161second # [ 2.500522] scsi host3: ahci1162second # [ 2.502523] scsi host4: ahci1163test # [ 2.477423] uhci_hcd 0000:00:1d.2: UHCI Host Controller1164test # [ 2.480752] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41165test # [ 2.484714] uhci_hcd 0000:00:1d.2: detected 2 ports1166second # [ 2.511603] scsi host5: ahci1167test # [ 2.486110] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01168second # [ 2.512889] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11169test # [ 2.487759] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1170test # [ 2.490182] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181171test # [ 2.491954] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1172test # [ 2.493452] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11173test # [ 2.495678] ahci 0000:00:1f.2: flags: 64bit ncq only1174second # [ 2.522293] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11175test # [ 2.497428] usb usb4: Product: UHCI Host Controller1176test # [ 2.499616] usb usb4: Manufacturer: Linux 6.18.54 uhci_hcd1177test # [ 2.500503] usb usb4: SerialNumber: 0000:00:1d.21178test # [ 2.503769] scsi host0: ahci1179second # [ 2.529253] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11180second # [ 2.533007] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11181test # [ 2.508829] scsi host1: ahci1182second # [ 2.534379] usb 1-1: new high-speed USB device number 2 using ehci-pci1183test # [ 2.509912] hub 4-0:1.0: USB hub found1184second # [ 2.535779] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11185test # [ 2.511889] scsi host2: ahci1186second # [ 2.537367] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11187test # [ 2.512869] hub 4-0:1.0: 2 ports detected1188test # [ 2.515737] scsi host3: ahci1189test # [ 2.519703] scsi host4: ahci1190test # [ 2.521362] scsi host5: ahci1191test # [ 2.526772] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11192test # [ 2.533628] usb 1-1: new high-speed USB device number 2 using ehci-pci1193test # [ 2.539082] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11194test # [ 2.545770] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11195test # [ 2.550642] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11196test # [ 2.552887] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11197test # [ 2.554340] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11198second # [ 2.665286] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001199second # [ 2.666673] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101200second # [ 2.668369] usb 1-1: Product: QEMU USB Tablet1201second # [ 2.669169] usb 1-1: Manufacturer: QEMU1202second # [ 2.670248] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11203second # [ 2.685849] hid: raw HID events driver (C) Jiri Kosina1204test # [ 2.664783] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001205test # [ 2.666250] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101206test # [ 2.668066] usb 1-1: Product: QEMU USB Tablet1207test # [ 2.669026] usb 1-1: Manufacturer: QEMU1208test # [ 2.669933] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11209test # [ 2.688349] hid: raw HID events driver (C) Jiri Kosina1210second # [ 2.586389] systemd[1]: Mounting /sysroot...1211test # [ 2.601148] systemd[1]: Mounting /sysroot...1212second # [ 2.851864] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1213second # [ 2.852972] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001214second # [ 2.854096] ata3.00: applying bridge limits1215second # [ 2.854988] ata3.00: configured for UDMA/1001216second # [ 2.855970] ata4: SATA link down (SStatus 0 SControl 300)1217second # [ 2.857101] ata6: SATA link down (SStatus 0 SControl 300)1218second # [ 2.858245] ata2: SATA link down (SStatus 0 SControl 300)1219second # [ 2.859366] ata5: SATA link down (SStatus 0 SControl 300)1220second # [ 2.860407] ata1: SATA link down (SStatus 0 SControl 300)1221second # [ 2.861850] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51222test # [ 2.869321] ata2: SATA link down (SStatus 0 SControl 300)1223test # [ 2.870664] ata1: SATA link down (SStatus 0 SControl 300)1224test # [ 2.872187] ata4: SATA link down (SStatus 0 SControl 300)1225test # [ 2.873561] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1226test # [ 2.874774] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001227test # [ 2.875899] ata3.00: applying bridge limits1228test # [ 2.876891] ata5: SATA link down (SStatus 0 SControl 300)1229test # [ 2.878054] ata6: SATA link down (SStatus 0 SControl 300)1230test # [ 2.879148] ata3.00: configured for UDMA/1001231test # [ 2.880381] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51232second # [ 2.917428] usbcore: registered new interface driver usbhid1233second # [ 2.924251] usbhid: USB HID core driver1234second # [ 2.938598] 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/input21235second # [ 2.946462] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01236second # [ 2.949897] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1237second # [ 2.958110] EXT4-fs (vda): mounted filesystem 57bfd2a7-f3f6-450a-a090-9dc8994d558e r/w with ordered data mode. Quota mode: none.1238test # [ 2.935185] usbcore: registered new interface driver usbhid1239second # [ 2.782598] systemd[1]: Mounted /sysroot.1240second # [ 2.786079] systemd[1]: Reached target Initrd Root File System.1241test # [ 2.941632] usbhid: USB HID core driver1242second # [ 2.968517] cdrom: Uniform CD-ROM driver Revision: 3.201243second # [ 2.789084] systemd[1]: Starting Mountpoints Configured in the Real Root...1244second # [ 2.805614] systemd-sysroot-fstab-check[136]: /sysroot should be mounted in the initrd, will request daemon-reload.1245test # [ 2.960688] 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/input21246second # [ 2.809395] systemd[1]: Reload requested from client PID 136 ('systemd-sysroot') (unit initrd-parse-etc.service)...1247second # [ 2.812619] systemd[1]: Reloading...1248test # [ 2.968063] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01249test # [ 2.974977] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1250test # [ 2.979563] EXT4-fs (vda): mounted filesystem d01fb6e5-30d5-460a-b0f0-876d1e7314a7 r/w with ordered data mode. Quota mode: none.1251test # [ 2.803813] systemd[1]: Mounted /sysroot.1252test # [ 2.805458] systemd[1]: Reached target Initrd Root File System.1253test # [ 2.990280] cdrom: Uniform CD-ROM driver Revision: 3.201254test # [ 2.809394] systemd[1]: Starting Mountpoints Configured in the Real Root...1255test # [ 2.825962] systemd-sysroot-fstab-check[137]: /sysroot should be mounted in the initrd, will request daemon-reload.1256test # [ 2.830430] systemd[1]: Reload requested from client PID 137 ('systemd-sysroot') (unit initrd-parse-etc.service)...1257test # [ 2.833084] systemd[1]: Reloading...1258second # [ 2.882607] systemd[1]: Reloading finished in 70 ms.1259second # [ 2.892827] systemd-sysroot-fstab-check[136]: Requesting initrd-fs.target/start/replace...1260second # [ 2.896122] systemd-sysroot-fstab-check[136]: Requesting swap.target/start/replace...1261second # [ 2.901156] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1262second # [ 2.902896] systemd[1]: Finished Mountpoints Configured in the Real Root.1263second # [ 2.904218] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1264test # [ 2.900698] systemd[1]: Reloading finished in 68 ms.1265test # [ 2.914586] systemd-sysroot-fstab-check[137]: Requesting initrd-fs.target/start/replace...1266test # [ 2.918173] systemd-sysroot-fstab-check[137]: Requesting swap.target/start/replace...1267test # [ 2.923949] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1268test # [ 2.925847] systemd[1]: Finished Mountpoints Configured in the Real Root.1269test # [ 2.927658] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1270second # [ 3.587106] systemd[1]: Mounting /sysroot/nix/.ro-store...1271second # [ 3.590088] systemd[1]: Mounting /sysroot/nix/.rw-store...1272second # [ 3.593911] systemd[1]: Mounting /sysroot/run...1273second # [ 3.597695] systemd[1]: Mounting /sysroot/tmp/shared...1274second # [ 3.602171] systemd[1]: Mounting /sysroot/tmp/xchg...1275second # [ 3.629327] systemd[1]: Mounted /sysroot/nix/.ro-store.1276test # [ 3.602143] systemd[1]: Mounting /sysroot/nix/.ro-store...1277second # [ 3.630911] systemd[1]: Mounted /sysroot/nix/.rw-store.1278test # [ 3.605157] systemd[1]: Mounting /sysroot/nix/.rw-store...1279second # [ 3.635080] systemd[1]: Mounted /sysroot/run.1280second # [ 3.638063] systemd[1]: Mounted /sysroot/tmp/shared.1281test # [ 3.610146] systemd[1]: Mounting /sysroot/run...1282second # [ 3.639440] systemd[1]: Mounted /sysroot/tmp/xchg.1283test # [ 3.615473] systemd[1]: Mounting /sysroot/tmp/shared...1284second # [ 3.643831] systemd[1]: Starting rw-sysroot-nix-store.service...1285test # [ 3.622159] systemd[1]: Mounting /sysroot/tmp/xchg...1286second # [ 3.654646] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1287second # [ 3.656586] systemd[1]: Finished rw-sysroot-nix-store.service.1288second # [ 3.659113] systemd[1]: Mounting /sysroot/nix/store...1289test # [ 3.640818] systemd[1]: Mounted /sysroot/run.1290test # [ 3.643094] systemd[1]: Mounted /sysroot/nix/.ro-store.1291test # [ 3.644206] systemd[1]: Mounted /sysroot/nix/.rw-store.1292second # [ 3.677327] systemd[1]: Mounted /sysroot/nix/store.1293test # [ 3.649380] systemd[1]: Mounted /sysroot/tmp/shared.1294second # [ 3.678984] systemd[1]: Reached target Initrd File Systems.1295test # [ 3.652189] systemd[1]: Mounted /sysroot/tmp/xchg.1296second # [ 3.680928] systemd[1]: Starting Find NixOS closure...1297second # [ 3.683743] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1298test # [ 3.655828] systemd[1]: Starting rw-sysroot-nix-store.service...1299test # [ 3.665560] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1300test # [ 3.667620] systemd[1]: Finished rw-sysroot-nix-store.service.1301test # [ 3.670118] systemd[1]: Mounting /sysroot/nix/store...1302second # [ 3.702727] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1303second # [ 3.707749] systemd[1]: Finished Find NixOS closure.1304second # [ 3.709185] systemd[1]: Reached target Initrd Default Target.1305second # [ 3.711092] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1306test # [ 3.689778] systemd[1]: Mounted /sysroot/nix/store.1307test # [ 3.690761] systemd[1]: Reached target Initrd File Systems.1308test # [ 3.692778] systemd[1]: Starting Find NixOS closure...1309second # [ 3.722901] systemd[1]: Stopped target Initrd Default Target.1310second # [ 3.724301] systemd[1]: Stopped target Basic System.1311test # [ 3.695702] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1312second # [ 3.726166] systemd[1]: Stopped target Initrd Root Device.1313second # [ 3.727241] systemd[1]: Stopped target Path Units.1314second # [ 3.728323] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1315second # [ 3.730255] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1316second # [ 3.732041] systemd[1]: Stopped target Slice Units.1317second # [ 3.733560] systemd[1]: Stopped target Socket Units.1318second # [ 3.734905] systemd[1]: Stopped target System Initialization.1319second # [ 3.736314] systemd[1]: Stopped target Swaps.1320second # [ 3.738058] systemd[1]: Stopped target Timer Units.1321second # [ 3.739105] systemd[1]: dbus.socket: Deactivated successfully.1322second # [ 3.740214] systemd[1]: Closed D-Bus System Message Bus Socket.1323second # [ 3.741465] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1324test # [ 3.712613] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1325second # [ 3.743153] systemd[1]: Stopped Find NixOS closure.1326second # [ 3.745134] systemd[1]: Starting rw-sysroot-nix-store.service...1327second # [ 3.746235] systemd[1]: systemd-sysctl.service: Deactivated successfully.1328second # [ 3.747601] systemd[1]: Stopped Apply Kernel Variables.1329second # [ 3.748869] systemd[1]: systemd-modules-load.service: Deactivated successfully.1330test # [ 3.721230] systemd[1]: Finished Find NixOS closure.1331second # [ 3.751167] systemd[1]: Stopped Load Kernel Modules.1332test # [ 3.722631] systemd[1]: Reached target Initrd Default Target.1333second # [ 3.752149] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1334test # [ 3.724661] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1335second # [ 3.753640] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1336second # [ 3.754990] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1337second # [ 3.757119] systemd[1]: Stopped Create System Files and Directories.1338second # [ 3.758283] systemd[1]: Stopped target Local File Systems.1339second # [ 3.759350] systemd[1]: Stopped target Preparation for Local File Systems.1340second # [ 3.760579] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1341second # [ 3.762171] systemd[1]: Stopped Coldplug All udev Devices.1342second # [ 3.763926] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1343test # [ 3.736787] systemd[1]: Stopped target Initrd Default Target.1344second # [ 3.766169] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1345test # [ 3.738599] systemd[1]: Stopped target Basic System.1346second # [ 3.767994] systemd[1]: Stopped Virtual Console Setup.1347test # [ 3.739982] systemd[1]: Stopped target Initrd Root Device.1348test # [ 3.742149] systemd[1]: Stopped target Path Units.1349test # [ 3.743130] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1350test # [ 3.744605] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1351test # [ 3.745985] systemd[1]: Stopped target Slice Units.1352second # [ 3.774747] systemd[1]: initrd-cleanup.service: Deactivated successfully.1353test # [ 3.747155] systemd[1]: Stopped target Socket Units.1354test # [ 3.748569] systemd[1]: Stopped target System Initialization.1355test # [ 3.749980] systemd[1]: Stopped target Swaps.1356test # [ 3.751872] systemd[1]: Stopped target Timer Units.1357second # [ 3.780284] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1358test # [ 3.752846] systemd[1]: dbus.socket: Deactivated successfully.1359test # [ 3.753963] systemd[1]: Closed D-Bus System Message Bus Socket.1360second # [ 3.783675] systemd[1]: systemd-udevd.service: Deactivated successfully.1361test # [ 3.755211] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1362test # [ 3.757149] systemd[1]: Stopped Find NixOS closure.1363second # [ 3.785188] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1364test # [ 3.758222] systemd[1]: Starting rw-sysroot-nix-store.service...1365test # [ 3.759466] systemd[1]: systemd-sysctl.service: Deactivated successfully.1366second # [ 3.788166] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1367test # [ 3.761183] systemd[1]: Stopped Apply Kernel Variables.1368second # [ 3.790166] systemd[1]: Finished rw-sysroot-nix-store.service.1369test # [ 3.763162] systemd[1]: systemd-modules-load.service: Deactivated successfully.1370test # [ 3.764457] systemd[1]: Stopped Load Kernel Modules.1371second # [ 3.792881] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1372test # [ 3.765717] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1373second # [ 3.795110] systemd[1]: Closed udev Control Socket.1374test # [ 3.767862] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1375second # [ 3.796537] systemd[1]: Starting Cleanup udev Database...1376test # [ 3.769240] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1377second # [ 3.798185] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1378test # [ 3.770529] systemd[1]: Stopped Create System Files and Directories.1379second # [ 3.799563] systemd[1]: Stopped Create Static Device Nodes in /dev.1380test # [ 3.771675] systemd[1]: Stopped target Local File Systems.1381second # [ 3.800797] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1382test # [ 3.774200] systemd[1]: Stopped target Preparation for Local File Systems.1383second # [ 3.803169] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1384test # [ 3.775683] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1385second # [ 3.804439] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1386second # [ 3.805682] systemd[1]: Stopped Create List of Static Device Nodes.1387test # [ 3.777538] systemd[1]: Stopped Coldplug All udev Devices.1388test # [ 3.779223] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1389test # [ 3.781308] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1390test # [ 3.783152] systemd[1]: Stopped Virtual Console Setup.1391test # [ 3.789876] systemd[1]: initrd-cleanup.service: Deactivated successfully.1392second # [ 3.819149] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1393second # [ 3.820771] systemd[1]: Finished Cleanup udev Database.1394test # [ 3.794226] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1395second # [ 3.823059] systemd[1]: Reached target Switch Root.1396second # [ 3.824205] systemd[1]: Starting NixOS Activation...1397test # [ 3.797509] systemd[1]: systemd-udevd.service: Deactivated successfully.1398test # [ 3.799352] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1399test # [ 3.801663] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1400test # [ 3.803149] systemd[1]: Finished rw-sysroot-nix-store.service.1401test # [ 3.806215] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1402test # [ 3.807553] systemd[1]: Closed udev Control Socket.1403test # [ 3.809085] systemd[1]: Starting Cleanup udev Database...1404test # [ 3.810145] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1405test # [ 3.812172] systemd[1]: Stopped Create Static Device Nodes in /dev.1406test # [ 3.813362] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1407test # [ 3.814817] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1408test # [ 3.816529] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1409test # [ 3.817802] systemd[1]: Stopped Create List of Static Device Nodes.1410test # [ 3.831299] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1411test # [ 3.832710] systemd[1]: Finished Cleanup udev Database.1412test # [ 3.834162] systemd[1]: Reached target Switch Root.1413test # [ 3.835790] systemd[1]: Starting NixOS Activation...1414second # [ 3.871198] initrd-nixos-activation-start[188]: booting system configuration /nix/store/w79m1hl5gbjrvxc7wqb4l1lnjgkhgz23-nixos-system-second-test1415second # [ 3.892133] initrd-nixos-activation-start[188]: running activation script...1416test # [ 3.884109] initrd-nixos-activation-start[189]: booting system configuration /nix/store/8f83hrdz2mq01n7jlxrjbsdxh73r414s-nixos-system-test-test1417test # [ 3.905079] initrd-nixos-activation-start[189]: running activation script...1418second # [ 4.046442] initrd-nixos-activation-start[211]: setting up /etc...1419test # [ 4.054781] initrd-nixos-activation-start[212]: setting up /etc...1420second # [ 4.122114] initrd-nixos-activation-start[211]: setting up age secrets...1421test # [ 4.134324] initrd-nixos-activation-start[212]: setting up age secrets...1422second # [ 4.198396] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1423second # [ 4.200416] systemd[1]: Finished NixOS Activation.1424second # [ 4.202544] systemd[1]: Starting Switch Root...1425second # [ 4.212757] systemd[1]: Switching root.1426test # [ 4.234405] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1427test # [ 4.236443] systemd[1]: Finished NixOS Activation.1428test # [ 4.238628] systemd[1]: Starting Switch Root...1429test # [ 4.250283] systemd[1]: Switching root.1430second # [ 4.519680] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).1431test # [ 4.561104] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).1432second # [ 8.730293] NET: Registered PF_VSOCK protocol family1433test # [ 8.706699] NET: Registered PF_VSOCK protocol family1434second # [ 9.084855] systemd[1]: systemd 261.2 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)1435test # [ 9.058944] systemd[1]: systemd 261.2 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 # [ 9.089404] systemd[1]: Detected virtualization kvm.1437test # [ 9.063568] systemd[1]: Detected virtualization kvm.1438second # [ 9.090279] systemd[1]: Detected architecture x86-64.1439test # [ 9.064416] systemd[1]: Detected architecture x86-64.1440second # [ 9.091223] systemd[1]: Detected first boot.1441test # [ 9.065354] systemd[1]: Detected first boot.1442second # [ 9.092856] systemd[1]: Initializing machine ID from random generator.1443test # [ 9.066941] systemd[1]: Initializing machine ID from random generator.1444test # [ 9.254020] systemd[1]: bpf-restrict-fs: LSM BPF program attached1445second # [ 9.284855] systemd[1]: bpf-restrict-fs: LSM BPF program attached1446second # [ 9.347603] systemd[1]: Applying preset policy.1447test # [ 9.323844] systemd[1]: Applying preset policy.1448test # [ 9.488033] systemd[1]: Populated /etc with preset unit settings.1449second # [ 9.529646] systemd[1]: Populated /etc with preset unit settings.1450test # [ 9.672327] systemd[1]: initrd-switch-root.service: Deactivated successfully.1451test # [ 9.674043] systemd[1]: Stopped initrd-switch-root.service.1452test # [ 9.676960] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1453test # [ 9.679327] systemd[1]: Created slice Slice /system/getty.1454test # [ 9.680824] systemd[1]: Created slice User and Session Slice.1455test # [ 9.681926] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1456test # [ 9.683334] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1457test # [ 9.684679] systemd[1]: Expecting device /dev/hvc0...1458test # [ 9.685583] systemd[1]: Expecting device /dev/ttyS0...1459test # [ 9.686517] systemd[1]: Reached target Local Encrypted Volumes.1460test # [ 9.687594] systemd[1]: Stopped target initrd-fs.target.1461second # [ 9.714930] systemd[1]: initrd-switch-root.service: Deactivated successfully.1462second # [ 9.716715] systemd[1]: Stopped initrd-switch-root.service.1463second # [ 9.719717] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1464second # [ 9.722063] systemd[1]: Created slice Slice /system/getty.1465test # [ 9.688517] systemd[1]: Stopped target initrd-root-fs.target.1466second # [ 9.723602] systemd[1]: Created slice User and Session Slice.1467test # [ 9.697923] systemd[1]: Stopped target initrd-switch-root.target.1468second # [ 9.724680] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1469test # [ 9.698991] systemd[1]: Reached target Virtual Machines and Containers.1470test # [ 9.700100] systemd[1]: Reached target Path Units.1471second # [ 9.726080] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1472test # [ 9.700998] systemd[1]: Reached target Remote File Systems.1473second # [ 9.727391] systemd[1]: Expecting device /dev/hvc0...1474test # [ 9.701986] systemd[1]: Reached target Slice Units.1475second # [ 9.728300] systemd[1]: Expecting device /dev/ttyS0...1476test # [ 9.702898] systemd[1]: Reached target Swaps.1477second # [ 9.729339] systemd[1]: Reached target Local Encrypted Volumes.1478second # [ 9.730363] systemd[1]: Stopped target initrd-fs.target.1479test # [ 9.704916] systemd[1]: Listening on Query the User Interactively for a Password.1480test # [ 9.707394] systemd[1]: Listening on Process Core Dump Socket.1481test # [ 9.709373] systemd[1]: Listening on Credential Encryption/Decryption.1482test # [ 9.711293] systemd[1]: Listening on Factory Reset Management.1483test # [ 9.712500] systemd[1]: Listening on Hostname Service Socket.1484second # [ 9.731289] systemd[1]: Stopped target initrd-root-fs.target.1485second # [ 9.740980] systemd[1]: Stopped target initrd-switch-root.target.1486test # [ 9.715351] systemd[1]: Starting Journal Log Access Socket...1487second # [ 9.742200] systemd[1]: Reached target Virtual Machines and Containers.1488test # [ 9.716898] systemd[1]: Listening on Journal Audit Socket.1489second # [ 9.743466] systemd[1]: Reached target Path Units.1490second # [ 9.744446] systemd[1]: Reached target Remote File Systems.1491second # [ 9.745539] systemd[1]: Reached target Slice Units.1492test # [ 9.719614] systemd[1]: Listening on Console Output Muting Service Socket.1493second # [ 9.746533] systemd[1]: Reached target Swaps.1494test # [ 9.720944] systemd[1]: Listening on Network Management Metrics Varlink Socket.1495test # [ 9.722388] systemd[1]: Listening on Network Management Varlink Socket.1496second # [ 9.748783] systemd[1]: Listening on Query the User Interactively for a Password.1497test # [ 9.723700] systemd[1]: Listening on Network Management Netlink Socket.1498test # [ 9.725014] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1499second # [ 9.751584] systemd[1]: Listening on Process Core Dump Socket.1500test # [ 9.726332] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1501second # [ 9.753585] systemd[1]: Listening on Credential Encryption/Decryption.1502test # [ 9.727941] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1503second # [ 9.755590] systemd[1]: Listening on Factory Reset Management.1504second # [ 9.756715] systemd[1]: Listening on Hostname Service Socket.1505test # [ 9.732682] systemd[1]: Listening on Disk Repartitioning Service Socket.1506second # [ 9.759663] systemd[1]: Starting Journal Log Access Socket...1507test # [ 9.734002] systemd[1]: Listening on Resolve Monitor Varlink Socket.1508test # [ 9.735209] systemd[1]: Listening on Resolve Service Varlink Socket.1509second # [ 9.762014] systemd[1]: Listening on Journal Audit Socket.1510test # [ 9.736433] systemd[1]: Listening on udev Control Socket.1511test # [ 9.737570] systemd[1]: Listening on udev Varlink Socket.1512second # [ 9.763940] systemd[1]: Listening on Console Output Muting Service Socket.1513second # [ 9.765315] systemd[1]: Listening on Network Management Metrics Varlink Socket.1514test # [ 9.739967] systemd[1]: Mounting Huge Pages File System...1515second # [ 9.766742] systemd[1]: Listening on Network Management Varlink Socket.1516second # [ 9.768007] systemd[1]: Listening on Network Management Netlink Socket.1517test # [ 9.742837] systemd[1]: Mounting POSIX Message Queue File System...1518second # [ 9.769315] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1519second # [ 9.770631] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1520second # [ 9.772214] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1521test # [ 9.748876] systemd[1]: Mounting Kernel Debug File System...1522second # [ 9.776119] systemd[1]: Listening on Disk Repartitioning Service Socket.1523second # [ 9.777398] systemd[1]: Listening on Resolve Monitor Varlink Socket.1524second # [ 9.778601] systemd[1]: Listening on Resolve Service Varlink Socket.1525second # [ 9.779787] systemd[1]: Listening on udev Control Socket.1526second # [ 9.780884] systemd[1]: Listening on udev Varlink Socket.1527test # [ 9.755856] systemd[1]: Mounting Kernel Trace File System...1528second # [ 9.783013] systemd[1]: Mounting Huge Pages File System...1529second # [ 9.786373] systemd[1]: Mounting POSIX Message Queue File System...1530test # [ 9.765308] systemd[1]: Starting Create List of Static Device Nodes...1531second # [ 9.793397] systemd[1]: Mounting Kernel Debug File System...1532second # [ 9.802050] systemd[1]: Mounting Kernel Trace File System...1533test # [ 9.778790] systemd[1]: Starting Load Kernel Module configfs...1534second # [ 9.811550] systemd[1]: Starting Create List of Static Device Nodes...1535test # [ 9.785805] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1536second # [ 9.827897] systemd[1]: Starting Load Kernel Module configfs...1537test # [ 9.803888] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1538second # [ 9.837698] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1539test # [ 9.813915] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1540test # [ 9.821366] systemd[1]: Mounting FUSE Control File System...1541test # [ 9.822440] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671542second # [ 9.851879] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1543second # [ 9.856414] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1544test # [ 9.832093] systemd[1]: Starting Journal Service...1545second # [ 9.860681] systemd[1]: Mounting FUSE Control File System...1546second # [ 9.864397] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671547test # [ 9.844876] systemd[1]: Starting Load Kernel Modules...1548second # [ 9.876442] systemd[1]: Starting Journal Service...1549test # [ 9.855490] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1550second # [ 9.884866] systemd[1]: Starting Load Kernel Modules...1551test # [ 9.871712] systemd[1]: Starting Remount Root and Kernel File Systems...1552test # [ 9.878237] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1553second # [ 9.907301] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1554second # [ 9.915399] systemd[1]: Starting Remount Root and Kernel File Systems...1555test # [ 9.890478] systemd-journald[337]: Collecting audit messages is enabled.1556second # [ 9.918439] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1557test # [ 9.899678] systemd[1]: Starting Coldplug All udev Devices...1558second # [ 9.934828] systemd-journald[315]: Collecting audit messages is enabled.1559second # [ 9.936141] systemd[1]: Starting Coldplug All udev Devices...1560test # [ 9.912442] systemd[1]: Listening on Journal Log Access Socket.1561test # [ 9.918812] systemd[1]: Mounted Huge Pages File System.1562test # [ 9.736836] systemd[1]: Queued start job for default target Multi-User System.1563test # [ 9.742404] systemd[1]: systemd-journald.service: Deactivated successfully.1564second # [ 9.952025] systemd[1]: Listening on Journal Log Access Socket.1565test # [ 9.748034] systemd-modules-load[338]: Module 'ctr' is built in1566second # [ 9.958557] systemd[1]: Mounted Huge Pages File System.1567test # [ 9.934704] systemd[1]: Started Journal Service.1568test # [ 9.937949] loop: module loaded1569second # [ 9.783657] systemd[1]: Queued start job for default target Multi-User System.1570second # [ 9.967450] systemd[1]: Started Journal Service.1571second # [ 9.968379] loop: module loaded1572test # [ 9.759873] systemd-modules-load[338]: Inserted module 'loop'1573second # [ 9.790567] systemd[1]: systemd-journald.service: Deactivated successfully.1574second # [ 9.792879] systemd-modules-load[316]: Module 'ctr' is built in1575test # [ 9.765360] systemd[1]: Mounted POSIX Message Queue File System.1576second # [ 9.796557] systemd-modules-load[316]: Inserted module 'loop'1577test # [ 9.770397] systemd[1]: Mounted Kernel Debug File System.1578test # [ 9.771622] systemd[1]: Mounted Kernel Trace File System.1579test # [ 9.776156] systemd[1]: Finished Create List of Static Device Nodes.1580second # [ 9.804967] systemd[1]: Mounted POSIX Message Queue File System.1581test # [ 9.777389] systemd[1]: modprobe@configfs.service: Deactivated successfully.1582second # [ 9.806395] systemd[1]: Mounted Kernel Debug File System.1583test # [ 9.783587] systemd[1]: Finished Load Kernel Module configfs.1584test # [ 9.968879] EXT4-fs (vda): re-mounted d01fb6e5-30d5-460a-b0f0-876d1e7314a7.1585second # [ 9.819097] systemd[1]: Mounted Kernel Trace File System.1586second # [ 10.001391] EXT4-fs (vda): re-mounted 57bfd2a7-f3f6-450a-a090-9dc8994d558e.1587test # [ 9.792121] systemd[1]: Mounted FUSE Control File System.1588test # [ 9.795108] systemd[1]: Finished Load Kernel Modules.1589second # [ 9.825265] systemd[1]: Finished Create List of Static Device Nodes.1590second # [ 9.826556] systemd[1]: modprobe@configfs.service: Deactivated successfully.1591second # [ 9.830218] systemd[1]: Finished Load Kernel Module configfs.1592second # [ 9.832385] systemd[1]: Mounted FUSE Control File System.1593test # [ 9.806854] systemd[1]: Finished Remount Root and Kernel File Systems.1594second # [ 9.836435] systemd[1]: Finished Load Kernel Modules.1595second # [ 9.839970] systemd[1]: Finished Remount Root and Kernel File Systems.1596test # [ 9.812166] systemd[1]: Listening on Disk Image Download Service Socket.1597test # [ 9.816736] systemd[1]: Mounting Kernel Configuration File System...1598test # [ 9.819466] systemd[1]: Starting Firewall...1599test # [ 9.823689] systemd-oomd[340]: No swap; memory pressure usage will be degraded1600second # [ 9.853127] systemd[1]: Listening on Disk Image Download Service Socket.1601second # [ 9.858131] systemd[1]: Mounting Kernel Configuration File System...1602test # [ 9.832493] systemd[1]: Starting Flush Journal to Persistent Storage...1603second # [ 9.861944] systemd[1]: Starting Firewall...1604test # [ 9.833809] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1605second # [ 9.866243] systemd-oomd[318]: No swap; memory pressure usage will be degraded1606second # [ 9.873134] systemd[1]: Starting Flush Journal to Persistent Storage...1607test # [ 9.846100] systemd[1]: Starting Load/Save OS Random Seed...1608second # [ 9.874489] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1609test # [ 9.856849] systemd[1]: Starting Apply Kernel Variables...1610second # [ 9.888092] systemd[1]: Starting Load/Save OS Random Seed...1611test # [ 9.867982] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1612test # [ 9.869488] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1613test # [ 9.872196] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1614second # [ 9.904963] systemd[1]: Starting Apply Kernel Variables...1615second # [ 9.916694] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1616second # [ 9.920107] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1617second # [ 9.923872] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1618test # [ 10.104216] systemd-journald[337]: Received client request to flush runtime journal.1619second # [ 10.134193] systemd-journald[315]: Received client request to flush runtime journal.1620test # [ 10.114710] systemd[1]: Finished Load/Save OS Random Seed.1621test # [ 10.119207] systemd[1]: Mounted Kernel Configuration File System.1622test # [ 10.120988] systemd[1]: Reached target First Boot Complete.1623test # [ 10.123747] systemd[1]: Finished Apply Kernel Variables.1624test # [ 10.124761] systemd[1]: Starting Network Name Resolution...1625test # [ 10.125927] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1626test # [ 10.127312] systemd[1]: Starting Create Static Device Nodes in /dev...1627test # [ 10.129327] systemd-resolved[366]: Positive Trust Anchors:1628test # [ 10.132243] systemd-resolved[366]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1629test # [ 10.134513] systemd-resolved[366]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161630second # [ 10.166762] systemd[1]: Mounted Kernel Configuration File System.1631test # [ 10.136622] systemd-resolved[366]: 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 test1632second # [ 10.171865] systemd[1]: Finished Load/Save OS Random Seed.1633second # [ 10.173385] systemd[1]: Reached target First Boot Complete.1634second # [ 10.175445] systemd[1]: Finished Apply Kernel Variables.1635second # [ 10.178352] systemd[1]: Starting Network Name Resolution...1636test # [ 10.150287] systemd[1]: Finished Flush Journal to Persistent Storage.1637second # [ 10.180467] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1638second # [ 10.182246] systemd[1]: Starting Create Static Device Nodes in /dev...1639second # [ 10.184470] systemd[1]: Finished Flush Journal to Persistent Storage.1640test # [ 10.166129] systemd[1]: Finished Create Static Device Nodes in /dev.1641test # [ 10.167394] systemd[1]: Reached target Preparation for Local File Systems.1642second # [ 10.195994] systemd-resolved[344]: Positive Trust Anchors:1643second # [ 10.198353] systemd-resolved[344]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1644test # [ 10.173227] systemd[1]: Starting Rule-based Manager for Device Events and Files...1645second # [ 10.202632] systemd-resolved[344]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161646second # [ 10.205246] systemd-resolved[344]: 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 test1647second # [ 10.236207] systemd[1]: Finished Create Static Device Nodes in /dev.1648test # [ 10.209686] systemd[1]: Finished Coldplug All udev Devices.1649second # [ 10.240084] systemd[1]: Reached target Preparation for Local File Systems.1650test # [ 10.214552] systemd-resolved[366]: Using system hostname 'test'.1651second # [ 10.245587] systemd[1]: Starting Rule-based Manager for Device Events and Files...1652test # [ 10.218878] systemd[1]: Started Network Name Resolution.1653second # [ 10.262179] systemd[1]: Finished Coldplug All udev Devices.1654test # [ 10.236710] systemd-udevd[381]: Using default interface naming scheme 'v261'.1655second # [ 10.275897] systemd-resolved[344]: Using system hostname 'second'.1656second # [ 10.281177] systemd[1]: Started Network Name Resolution.1657second # [ 10.304745] systemd-udevd[361]: Using default interface naming scheme 'v261'.1658test # [ 10.293283] systemd[1]: Started Rule-based Manager for Device Events and Files.1659second # [ 10.364128] systemd[1]: Started Rule-based Manager for Device Events and Files.1660test # [ 10.434476] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1661second # [ 10.497458] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1662test # [ 10.494979] systemd[1]: Mounting /run/wrappers...1663second # [ 10.537885] systemd[1]: Mounting /run/wrappers...1664test # [ 10.533257] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1665test # [ 10.536510] systemd[1]: Mounted /run/wrappers.1666test # [ 10.537696] systemd[1]: Reached target Local File Systems.1667test # [ 10.541100] systemd[1]: Listening on Boot Loader Control Service Socket.1668test # [ 10.545097] systemd[1]: Starting register-nix-paths.service...1669second # [ 10.574665] systemd[1]: Mounted /run/wrappers.1670second # [ 10.575822] systemd[1]: Reached target Local File Systems.1671test # [ 10.550063] systemd[1]: Starting Create SUID/SGID Wrappers...1672second # [ 10.579468] systemd[1]: Listening on Boot Loader Control Service Socket.1673test # [ 10.551173] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1674second # [ 10.584200] systemd[1]: Starting register-nix-paths.service...1675test # [ 10.557326] systemd[1]: Starting Save Transient machine-id to Disk...1676second # [ 10.589118] systemd[1]: Starting Create SUID/SGID Wrappers...1677second # [ 10.590826] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1678test # [ 10.574235] systemd[1]: Starting Create System Files and Directories...1679second # [ 10.604128] systemd[1]: Starting Save Transient machine-id to Disk...1680test # [ 10.585237] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1681second # [ 10.622753] systemd[1]: Starting Create System Files and Directories...1682second # [ 10.651185] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1683test # [ 10.653087] (udev-worker)[406]: Network interface NamePolicy= disabled on kernel command line.1684test # [ 10.659614] (udev-worker)[407]: Network interface NamePolicy= disabled on kernel command line.1685second # [ 10.706458] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1686test # [ 10.739549] systemd[1]: Finished Create System Files and Directories.1687second # [ 10.773327] systemd[1]: Finished Create System Files and Directories.1688test # [ 10.746434] systemd[1]: Starting Rebuild Journal Catalog...1689test # [ 10.752341] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1690second # [ 10.783658] (udev-worker)[388]: Network interface NamePolicy= disabled on kernel command line.1691second # [ 10.791778] systemd[1]: Starting Rebuild Journal Catalog...1692second # [ 10.794314] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1693second # [ 10.832490] (udev-worker)[385]: Network interface NamePolicy= disabled on kernel command line.1694test # [ 10.825135] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1695second # [ 10.884456] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1696test # [ 10.860121] systemd[1]: Finished Rebuild Journal Catalog.1697test # [ 10.867444] systemd[1]: Starting Update is Completed...1698second # [ 10.915166] systemd[1]: Finished Rebuild Journal Catalog.1699second # [ 10.924614] systemd[1]: Starting Update is Completed...1700test # [ 10.934314] systemd[1]: Finished Update is Completed.1701second # [ 10.987160] systemd[1]: Finished Update is Completed.1702test # [ 11.115936] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1703test # [ 11.120762] systemd[1]: Finished Create SUID/SGID Wrappers.1704second # [ 11.172855] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1705second # [ 11.177801] systemd[1]: Finished Create SUID/SGID Wrappers.1706test # [ 11.340779] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31707test # [ 11.161422] systemd[1]: Finished Firewall.1708test # [ 11.165106] systemd[1]: Reached target Preparation for Network.1709test # [ 11.166370] systemd[1]: Listening on Network Management Resolve Hook Socket.1710test # [ 11.172614] systemd[1]: Starting Network Management...1711second # [ 11.234415] systemd[1]: Finished Firewall.1712second # [ 11.235448] systemd[1]: Reached target Preparation for Network.1713test # [ 11.391562] mousedev: PS/2 mouse device common for all mice1714second # [ 11.236646] systemd[1]: Listening on Network Management Resolve Hook Socket.1715second # [ 11.241644] systemd[1]: Starting Network Management...1716test # [ 11.398578] ACPI: button: Power Button [PWRF]1717second # [ 11.432085] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31718test # [ 11.259710] systemd[1]: Finished register-nix-paths.service.1719test # [ 11.260914] systemd[1]: Reached target System Initialization.1720second # [ 11.472225] ACPI: button: Power Button [PWRF]1721test # [ 11.265698] systemd[1]: Started Discard unused filesystem blocks once a week.1722test # [ 11.267035] systemd[1]: Started Daily Cleanup of Temporary Directories.1723test # [ 11.268341] systemd[1]: Reached target Timer Units.1724test # [ 11.269334] systemd[1]: Listening on D-Bus System Message Bus Socket.1725test # [ 11.272411] systemd[1]: Listening on Nix Daemon Socket.1726second # [ 11.485001] mousedev: PS/2 mouse device common for all mice1727second # [ 11.312936] systemd[1]: Finished register-nix-paths.service.1728test # [ 11.285099] systemd[1]: Listening on Authorization Manager Agent Helper.1729test # [ 11.286373] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1730test # [ 11.287849] systemd[1]: Reached target Socket Units.1731second # [ 11.316224] systemd[1]: Reached target System Initialization.1732second # [ 11.318129] systemd[1]: Started Discard unused filesystem blocks once a week.1733second # [ 11.319993] systemd[1]: Started Daily Cleanup of Temporary Directories.1734test # [ 11.293863] systemd[1]: Starting D-Bus System Message Bus...1735second # [ 11.323557] systemd[1]: Reached target Timer Units.1736second # [ 11.325348] systemd[1]: Listening on D-Bus System Message Bus Socket.1737second # [ 11.326599] systemd[1]: Listening on Nix Daemon Socket.1738second # [ 11.332237] systemd[1]: Listening on Authorization Manager Agent Helper.1739second # [ 11.333465] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1740second # [ 11.334945] systemd[1]: Reached target Socket Units.1741test # [ 11.493908] rtc_cmos PNP0B00:00: RTC can wake from S41742second # [ 11.339510] systemd[1]: Starting D-Bus System Message Bus...1743test # [ 11.505149] parport_pc 00:02: reported by Plug and Play ACPI1744test # [ 11.511613] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1745test # [ 11.521715] rtc_cmos PNP0B00:00: registered as rtc01746test # [ 11.522696] rtc_cmos PNP0B00:00: setting system clock to 2026-09-27T20:10:46 UTC (1790539846)1747test # [ 11.524393] systemd-journald[337]: Time jumped backwards, rotating.1748second # [ 11.570653] rtc_cmos PNP0B00:00: RTC can wake from S41749second # [ 11.575831] parport_pc 00:02: reported by Plug and Play ACPI1750second # [ 11.576877] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1751test # [ 11.384333] systemd-resolved[366]: Clock change detected. Flushing caches.1752second # [ 11.613011] rtc_cmos PNP0B00:00: registered as rtc01753second # [ 11.623459] rtc_cmos PNP0B00:00: setting system clock to 2026-09-27T20:10:46 UTC (1790539846)1754second # [ 11.625397] systemd-journald[315]: Time jumped backwards, rotating.1755second # [ 11.634305] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1756second # [ 11.641870] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input41757second # [ 11.643929] cfg80211: Loading compiled-in X.509 certificates for regulatory database1758test # [ 11.438402] dbus-broker-launch[564]: Looking up NSS user entry for 'systemd-timesync'...1759second # [ 11.648590] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1760test # [ 11.442702] systemd-networkd[560]: /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.1761test # [ 11.447289] systemd-networkd[560]: /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.1762test # [ 11.451319] dbus-broker-launch[564]: NSS returned no entry for 'systemd-timesync'1763test # [ 11.453920] dbus-broker-launch[564]: Invalid user-name in /nix/store/nizpdw05vqv7gssybrdr45l0i6d31b0c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1764second # [ 11.484877] systemd-resolved[344]: Clock change detected. Flushing caches.1765test # [ 11.457160] systemd-networkd[560]: lo: Link UP1766test # [ 11.458187] systemd-networkd[560]: lo: Gained carrier1767test # [ 11.466144] systemd[1]: Started Network Management.1768test # [ 11.470416] systemd-networkd[560]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1769test # [ 11.474300] systemd-networkd[560]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1770test # [ 11.477428] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1771test # [ 11.480815] systemd-networkd[560]: eth1: Link UP1772test # [ 11.484126] systemd-networkd[560]: eth1: Gained carrier1773test # [ 11.485232] systemd-networkd[560]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1774second # [ 11.513972] dbus-broker-launch[543]: Looking up NSS user entry for 'systemd-timesync'...1775second # [ 11.519660] dbus-broker-launch[543]: NSS returned no entry for 'systemd-timesync'1776second # [ 11.521294] dbus-broker-launch[543]: Invalid user-name in /nix/store/nizpdw05vqv7gssybrdr45l0i6d31b0c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1777test # [ 11.576638] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1778second # [ 11.535398] systemd-networkd[539]: /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.1779test # [ 11.508916] dbus-broker-launch[564]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/80ww8vwb347f6hpxip20p9ns9qm2s2v8-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1780second # [ 11.540423] systemd-networkd[539]: /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.1781test # [ 11.513407] dbus-broker-launch[564]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/80ww8vwb347f6hpxip20p9ns9qm2s2v8-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1782second # [ 11.545075] systemd-networkd[539]: lo: Link UP1783second # [ 11.545990] systemd-networkd[539]: lo: Gained carrier1784test # [ 11.523134] dbus-broker-launch[564]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/xw5lmid8j3918qh55xaw8jps6vbjq4cv-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1785second # [ 11.555105] systemd[1]: Started Network Management.1786test # [ 11.527241] dbus-broker-launch[564]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/zv9j8ckqlivmnmiwjspkn28jc5l3ls0m-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1787second # [ 11.556292] systemd-networkd[539]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1788second # [ 11.558822] systemd-networkd[539]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1789test # [ 11.533075] systemd[1]: Started D-Bus System Message Bus.1790test # [ 11.535111] systemd[1]: Reached target Basic System.1791second # [ 11.565743] systemd-networkd[539]: eth1: Link UP1792second # [ 11.566858] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1793test # [ 11.541161] systemd[1]: Starting Network Manager Script Dispatcher Service...1794second # [ 11.569857] dbus-broker-launch[543]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/80ww8vwb347f6hpxip20p9ns9qm2s2v8-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1795test # [ 11.546335] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1796second # [ 11.575425] systemd-networkd[539]: eth1: Gained carrier1797second # [ 11.578413] systemd-networkd[539]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1798second # [ 11.580974] dbus-broker-launch[543]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/80ww8vwb347f6hpxip20p9ns9qm2s2v8-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1799second # [ 11.585447] dbus-broker-launch[543]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/xw5lmid8j3918qh55xaw8jps6vbjq4cv-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1800test # [ 11.563102] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1801second # [ 11.591059] dbus-broker-launch[543]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/zv9j8ckqlivmnmiwjspkn28jc5l3ls0m-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1802second # [ 11.598319] systemd[1]: Started D-Bus System Message Bus.1803second # [ 11.599411] systemd[1]: Reached target Basic System.1804test # [ 11.576266] dbus-broker-launch[564]: Ready1805second # [ 11.607540] systemd[1]: Starting Network Manager Script Dispatcher Service...1806test # [ 11.583089] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1807second # [ 11.616112] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1808second # [ 11.623140] systemd[1]: Starting Network Manager...1809test # [ 11.599339] systemd[1]: Starting Network Manager...1810test # [ 11.577618] cfg80211: Loading compiled-in X.509 certificates for regulatory database1811test # [ 11.596443] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input41812test # [ 11.613731] systemd[1]: Started backdoor.service.1813second # [ 11.646861] dbus-broker-launch[543]: Ready1814second # [ 11.647925] systemd[1]: Started backdoor.service.1815test # [ 11.627102] systemd[1]: Starting Import lastlog data into lastlog2 database...1816second # [ 11.675193] systemd[1]: Starting Import lastlog data into lastlog2 database...1817test # [ 11.655106] csrsqhskch16fs3hk832ykw1r2z9maxh-wifi-secrets[576]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1818second # [ 11.688988] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1819test # [ 11.667727] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1820second # [ 11.714434] systemd[1]: Starting Post-Boot Actions...1821test # [ 11.685235] vb8pc9wcqy39qa3q5alv51h0jms1a1wl-wifi-secrets[577]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1822test # [ 11.700869] systemd[1]: Starting Post-Boot Actions...1823test # [ 11.705193] 7x3chz0b0917ic6jha19q7m5hcdq301r-wifi-secrets[578]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1824second # [ 11.736170] systemd[1]: Started Reset console on configuration changes.1825second # [ 11.757108] csrsqhskch16fs3hk832ykw1r2z9maxh-wifi-secrets[555]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1826test # [ 11.732981] systemd[1]: Started Reset console on configuration changes.1827second # [ 11.763684] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1828test # [ 11.597032] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1829test # [ 11.598773] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1830test # [ 11.745421] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1831second # connecting to host...1832test # connecting to host...1833second # [ 11.814568] systemd[1]: Finished Save Transient machine-id to Disk.1834second # [ 11.829660] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1835second # [ 11.837499] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1836test # [ 11.811162] systemd[1]: Finished Save Transient machine-id to Disk.1837second # [ 11.847160] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1838test # [ 11.820827] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1839test # [ 11.600826] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1840test # [ 11.600921] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1841test # [ 11.826375] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1842test: Guest shell says: b'Spawning backdoor root shell...\n'1843test # [ 11.831703] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1844test # [ 11.837352] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1845test: connected to guest root shell1846test # [ 11.845849] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1847test: (connecting took 12.58 seconds)1848test: (finished: waiting for the VM to finish booting, in 12.58 seconds)1849test # [ 11.847113] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1850test # [ 11.853440] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1851second # [ 11.688491] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1852second # [ 11.688582] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1853second # [ 11.902826] systemd[1]: Finished Post-Boot Actions.1854test # [ 11.875566] nsncd[584]: Sep 27 20:10:47.033 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1855test # [ 11.882145] systemd[1]: Started Name Service Cache Daemon (nsncd).1856second # [ 11.910320] nsncd[560]: Sep 27 20:10:46.966 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1857second # [ 11.915871] systemd[1]: Started Name Service Cache Daemon (nsncd).1858test # [ 11.681209] Console: switching to colour dummy device 80x251859test # [ 11.688248] NET: Registered PF_PACKET protocol family1860test # [ 12.058129] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61861test # [ 11.899456] systemd[1]: Finished Post-Boot Actions.1862second # [ 11.929654] systemd[1]: Started Network Manager Script Dispatcher Service.1863second # [ 11.937464] systemd[1]: Reached target Host and Network Name Lookups.1864second # [ 11.938680] systemd[1]: Reached target User and Group Name Lookups.1865second # [ 11.948824] systemd[1]: Starting User Login Management...1866second # [ 11.706285] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1867second # [ 11.806972] NET: Registered PF_PACKET protocol family1868second # [ 11.959970] systemd[1]: Finished Import lastlog data into lastlog2 database.1869test # [ 11.936295] systemd[1]: Started Network Manager Script Dispatcher Service.1870test # [ 11.950487] systemd[1]: Finished Import lastlog data into lastlog2 database.1871second # [ 11.981519] systemd[1]: Starting Hostname Service...1872test # [ 12.058426] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51873second # [ 12.070187] Console: switching to colour dummy device 80x251874second # [ 12.148518] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1875second # [ 12.148636] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1876test # [ 11.971643] systemd[1]: Reached target Host and Network Name Lookups.1877test # [ 11.972860] systemd[1]: Reached target User and Group Name Lookups.1878test # [ 11.978613] systemd[1]: Starting Hostname Service...1879test # [ 12.076021] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1880test # [ 12.076132] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1881test # [ 11.986719] systemd[1]: Starting User Login Management...1882second # [ 12.199894] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61883second # [ 12.200239] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51884test # [ 12.205578] [drm] Found bochs VGA, ID 0xb0c5.1885test # [ 12.206128] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1886second # [ 12.259796] [drm] Found bochs VGA, ID 0xb0c5.1887second # [ 12.259799] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1888second # [ 12.081612] systemd-logind[596]: New seat seat0.1889second # [ 12.085157] systemd[1]: Started User Login Management.1890second # [ 12.270252] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1891second # [ 12.271102] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01892second # [ 12.092476] systemd[1]: Starting linger-users.service...1893test # [ 12.065314] systemd[1]: Starting Virtual Console Setup...1894second # [ 12.105857] systemd[1]: Started Hostname Service.1895test # [ 12.270172] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1896test # [ 12.090526] systemd-logind[638]: New seat seat0.1897test # [ 12.096113] systemd[1]: Started User Login Management.1898test # [ 12.105146] systemd[1]: Starting linger-users.service...1899second # [ 12.135431] systemd[1]: Starting Virtual Console Setup...1900test # [ 12.295199] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01901second # [ 12.151116] systemd[1]: linger-users.service: Deactivated successfully.1902test # [ 12.123244] systemd[1]: Started Hostname Service.1903second # [ 12.153674] systemd[1]: Finished linger-users.service.1904test # [ 12.135323] systemd-logind[638]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1905test # [ 12.156100] systemd[1]: linger-users.service: Deactivated successfully.1906test # [ 12.160504] systemd[1]: Finished linger-users.service.1907second # [ 12.202100] systemd-networkd[539]: eth0: Link UP1908second # [ 12.203274] systemd-networkd[539]: eth0: Gained carrier1909test # [ 12.196821] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1910test # [ 12.199644] systemd-logind[638]: Watching system buttons on /dev/input/event2 (Power Button)1911second # [ 12.232074] systemd[1]: Started Network Manager.1912second # [ 12.233090] systemd[1]: Reached target Network.1913test # [ 12.206113] systemd-networkd[560]: eth0: Link UP1914test # [ 12.207533] systemd-networkd[560]: eth0: Gained carrier1915second # [ 12.239918] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1916second # [ 12.244493] systemd[1]: Starting Permit User Sessions...1917test # [ 12.233286] systemd[1]: Started Network Manager.1918test # [ 12.239104] systemd[1]: Reached target Network.1919test # [ 12.243118] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1920test # [ 12.249159] systemd[1]: Starting Permit User Sessions...1921second # [ 12.278185] systemd-logind[596]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1922second # [ 12.301140] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1923second # [ 12.321092] systemd-logind[596]: Watching system buttons on /dev/input/event2 (Power Button)1924test # [ 12.377104] systemd[1]: Finished Permit User Sessions.1925second # [ 12.410629] systemd[1]: Finished Permit User Sessions.1926second # [ 12.423086] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1927second # [ 12.430087] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1928second # [ 12.588713] Console: switching to colour frame buffer device 160x501929second # [ 12.630630] ppdev: user-space parallel port driver1930test # [ 12.427593] NetworkManager[579]: <warn> [1790539847.5857] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1931second # [ 12.648696] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1932second # [ 12.470247] systemd[1]: Started Getty on tty1.1933second # [ 12.474225] systemd[1]: Reached target Login Prompts.1934second # [ 12.475362] systemd[1]: Reached target Multi-User System.1935test # [ 12.558394] ppdev: user-space parallel port driver1936test # [ 12.460751] systemd-logind[638]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1937test # [ 12.631668] Console: switching to colour frame buffer device 160x501938test # [ 12.648317] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1939test # [ 12.466809] systemd[1]: Started Getty on tty1.1940test # [ 12.468477] systemd[1]: Reached target Login Prompts.1941test # [ 12.472079] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1942test # [ 12.474244] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1943second # [ 12.507342] systemd-logind[596]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1944test # [ 12.483492] systemd[1]: Reached target Multi-User System.1945second # [ 12.699826] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1946test # [ 12.491314] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1947test # [ 12.494265] systemd[1]: Stopped Virtual Console Setup.1948second # [ 12.523261] systemd-resolved[344]: eth0: Bus client set default route setting: yes1949second # [ 12.526965] systemd-resolved[344]: eth0: Bus client set DNS server list to: 10.0.2.31950test # [ 12.504815] systemd-resolved[366]: eth0: Bus client set default route setting: yes1951test # [ 12.508940] systemd-resolved[366]: eth0: Bus client set DNS server list to: 10.0.2.31952test # [ 12.696344] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1953test # [ 12.728768] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1954second # [ 12.573448] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1955second # [ 12.577533] systemd[1]: Stopped Virtual Console Setup.1956second # [ 12.768177] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1957second # [ 12.595071] systemd[1]: Starting Virtual Console Setup...1958test # [ 12.842033] kvm_amd: TSC scaling supported1959test # [ 12.842796] kvm_amd: Nested Virtualization enabled1960test # [ 12.843453] kvm_amd: Nested Paging enabled1961test # [ 12.844559] kvm_amd: LBR virtualization supported1962test # [ 12.845192] kvm_amd: Virtual GIF supported1963test # [ 12.845979] kvm_amd: Virtual NMI enabled1964second # [ 12.888364] kvm_amd: TSC scaling supported1965second # [ 12.888910] kvm_amd: Nested Virtualization enabled1966second # [ 12.889887] kvm_amd: Nested Paging enabled1967second # [ 12.890655] kvm_amd: LBR virtualization supported1968second # [ 12.891579] kvm_amd: Virtual GIF supported1969second # [ 12.892105] kvm_amd: Virtual NMI enabled1970test # [ 12.875649] EDAC MC: Ver: 3.0.01971second # [ 12.922812] EDAC MC: Ver: 3.0.01972test # [ 12.715384] systemd[1]: Starting Virtual Console Setup...1973second # [ 12.744962] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1974second # [ 12.747845] systemd[1]: Stopped Virtual Console Setup.1975second # [ 12.757828] systemd[1]: Starting Virtual Console Setup...1976test # [ 12.752704] systemd-networkd[560]: eth1: Gained IPv6LL1977test # [ 12.823220] systemd-vconsole-setup[690]: Configuration of first virtual console was skipped, ignoring remaining ones.1978test # [ 12.827320] systemd[1]: Finished Virtual Console Setup.1979second # [ 12.858949] systemd-vconsole-setup[649]: Configuration of first virtual console was skipped, ignoring remaining ones.1980test # [ 12.831264] systemd[1]: Startup finished in 881ms (kernel) + 7.602s (initrd) + 4.346s (userspace) = 12.830s.1981second # [ 12.862906] systemd[1]: Finished Virtual Console Setup.1982second # [ 12.867211] systemd[1]: Startup finished in 866ms (kernel) + 7.646s (initrd) + 4.352s (userspace) = 12.866s.1983second # [ 13.009128] systemd-networkd[539]: eth1: Gained IPv6LL1984test: (finished: waiting for unit NetworkManager.service, in 13.73 seconds)1985test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1986test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.01 seconds)1987test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets1988test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets, in 0.01 seconds)1989test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection1990test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.01 seconds)1991(finished: run the VM test script, in 13.91 seconds)1992second # [ 13.586445] systemd-networkd[539]: eth0: Gained IPv6LL1993test # [ 13.906128] systemd-networkd[560]: eth0: Gained IPv6LL1994test script finished in 15.36s1995cleanup1996kill QemuMachine (pid 47)1997second # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14)1998second # [2026-09-27T20:10:49Z INFO virtiofsd] Client disconnected, shutting down1999second # [2026-09-27T20:10:49Z INFO virtiofsd] Client disconnected, shutting down2000second # [2026-09-27T20:10:49Z INFO virtiofsd] Client disconnected, shutting down2001kill QemuMachine (pid 48)2002test # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14)2003test # [2026-09-27T20:10:50Z INFO virtiofsd] Client disconnected, shutting down2004test # [2026-09-27T20:10:50Z INFO virtiofsd] Client disconnected, shutting down2005test # [2026-09-27T20:10:50Z INFO virtiofsd] Client disconnected, shutting down2006(finished: cleanup, in 0.25 seconds)