vm-test-run-wifi
checks.x86_64-linux.wifi
· build #111
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)4Test will time out and terminate in 3600.0 seconds5run the VM test script6additionally exposed symbols:7 second, test,8 vlan1,9 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_ssh10start all VMs11second: starting vm12test: starting vm13test # Disk image does not exist, creating the virtualisation disk image...14test # Formatting '/build/vm-state-test/tmp.PEjYnPEDiK', fmt=raw size=107374182415test # mke2fs 1.47.4 (6-Mar-2025)16test # Discarding device blocks: 0/262144 done17test # Creating filesystem with 262144 4k blocks and 65536 inodes18test # Filesystem UUID: 9704362a-48c7-4c67-83e4-f373960181eb19test # Superblock backups stored on blocks:20test # 32768, 98304, 163840, 22937621test # 22test # Allocating group tables: 0/8 done23test # Writing inode tables: 0/8 done24test # Creating journal (8192 blocks): done25test # Writing superblocks and filesystem accounting information: 0/8 done26test # 27test # Virtualisation disk image created.28test: QEMU running (pid 47)29second # Disk image does not exist, creating the virtualisation disk image...30second # Formatting '/build/vm-state-second/tmp.ofxuCQdLPW', fmt=raw size=107374182431second # mke2fs 1.47.4 (6-Mar-2025)32second # Discarding device blocks: 0/262144 done33second # Creating filesystem with 262144 4k blocks and 65536 inodes34second # Filesystem UUID: 3da37e5b-65d2-44bd-9b2b-f04c95d148cf35second # Superblock backups stored on blocks:36second # 32768, 98304, 163840, 22937637second # 38second # Allocating group tables: 0/8 done39second # Writing inode tables: 0/8 done40second # Creating journal (8192 blocks): done41second # Writing superblocks and filesystem accounting information: 0/8 done42second # 43second # Virtualisation disk image created.44second: QEMU running (pid 48)45(finished: start all VMs, in 0.09 seconds)46test: waiting for unit NetworkManager.service47test: waiting for the VM to finish booting48test # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)49second # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)50test # 51test # 52test # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0053second # 54second # 55second # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0056test # Press Ctrl-B to configure iPXE (PCI 00:02.0)...57test # 58test # 59test # 60test # 61test # iPXE (http://ipxe.org) 00:08.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0062second # Press Ctrl-B to configure iPXE (PCI 00:02.0)...63second # 64second # 65second # 66second # 67second # iPXE (http://ipxe.org) 00:08.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0068test # Press Ctrl-B to configure iPXE (PCI 00:08.0)...69test # 70test # 71second # Press Ctrl-B to configure iPXE (PCI 00:08.0)...72second # 73second # 74test # Booting from ROM...75second # Booting from ROM...76test # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.49 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Wed Sep 2 12:31:51 UTC 202677test # [ 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/swxa86y3wmqx1dr86miw20bagz8rxc7l-nixos-system-test-test/init regInfo=/nix/store/xdi2dl0yv3lc6ql6cj4x29pb15jsldpa-closure-info/registration console=ttyS0,115200n8 console=tty078test # [ 0.000000] BIOS-provided physical RAM map:79test # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable80test # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved81test # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved82test # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable83test # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved84test # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved85test # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved86test # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved87test # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved88test # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved89test # [ 0.000000] NX (Execute Disable) protection: active90test # [ 0.000000] APIC: Static calls initialized91second # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.49 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Wed Sep 2 12:31:51 UTC 202692test # [ 0.000000] SMBIOS 2.8 present.93test # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/201494test # [ 0.000000] DMI: Memory slots populated: 1/195test # [ 0.000000] Hypervisor detected: KVM96test # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x40000000097second # [ 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/41grk726jb1nqr68nw7vkjsf12lbnw7r-nixos-system-second-test/init regInfo=/nix/store/7h6vr552qvrsg66hhmv9f619mb9h95d8-closure-info/registration console=ttyS0,115200n8 console=tty098test # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d0099second # [ 0.000000] BIOS-provided physical RAM map:100test # [ 0.000000] kvm-clock: using sched offset of 418002240 cycles101second # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable102test # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns103second # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved104test # [ 0.000006] tsc: Detected 4192.120 MHz processor105test # [ 0.000683] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000106second # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved107second # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable108test # [ 0.000723] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs109test # [ 0.000726] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT110second # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved111test # [ 0.002524] found SMP MP-table at [mem 0x000f5450-0x000f545f]112second # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved113test # [ 0.002539] Using GB pages for direct mapping114test # [ 0.002634] RAMDISK: [mem 0x3e36a000-0x3ffcffff]115second # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved116test # [ 0.002642] ACPI: Early table checksum verification disabled117second # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved118test # [ 0.002645] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )119second # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved120test # [ 0.002649] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)121second # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved122test # [ 0.002653] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)123second # [ 0.000000] NX (Execute Disable) protection: active124second # [ 0.000000] APIC: Static calls initialized125second # [ 0.000000] SMBIOS 2.8 present.126test # [ 0.002659] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)127test # [ 0.002660] ACPI: FACS 0x000000003FFE0000 000040128second # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014129test # [ 0.002661] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)130second # [ 0.000000] DMI: Memory slots populated: 1/1131second # [ 0.000000] Hypervisor detected: KVM132test # [ 0.002663] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)133second # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000134test # [ 0.002664] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)135second # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00136second # [ 0.000000] kvm-clock: using sched offset of 432160144 cycles137test # [ 0.002665] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)138test # [ 0.002666] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]139second # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns140test # [ 0.002667] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]141second # [ 0.000005] tsc: Detected 4192.120 MHz processor142test # [ 0.002667] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]143second # [ 0.000616] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000144test # [ 0.002668] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]145second # [ 0.000646] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs146test # [ 0.002668] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]147second # [ 0.000648] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT148test # [ 0.002668] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]149second # [ 0.002384] found SMP MP-table at [mem 0x000f5450-0x000f545f]150second # [ 0.002399] Using GB pages for direct mapping151test # [ 0.002669] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]152second # [ 0.002466] RAMDISK: [mem 0x3e36b000-0x3ffcffff]153test # [ 0.003052] No NUMA configuration found154second # [ 0.002472] ACPI: Early table checksum verification disabled155test # [ 0.003053] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]156second # [ 0.002475] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )157test # [ 0.003057] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]158test # [ 0.003135] Zone ranges:159second # [ 0.002479] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)160test # [ 0.003136] DMA [mem 0x0000000000001000-0x0000000000ffffff]161second # [ 0.002483] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)162test # [ 0.003137] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]163test # [ 0.003138] Normal empty164test # [ 0.003139] Device empty165second # [ 0.002489] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)166test # [ 0.003139] Movable zone start for each node167second # [ 0.002491] ACPI: FACS 0x000000003FFE0000 000040168test # [ 0.003140] Early memory node ranges169test # [ 0.003140] node 0: [mem 0x0000000000001000-0x000000000009efff]170second # [ 0.002492] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)171test # [ 0.003141] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]172second # [ 0.002493] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)173test # [ 0.003142] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]174second # [ 0.002494] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)175test # [ 0.003158] On node 0, zone DMA: 1 pages in unavailable ranges176test # [ 0.003375] On node 0, zone DMA: 97 pages in unavailable ranges177second # [ 0.002496] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)178test # [ 0.017859] On node 0, zone DMA32: 40 pages in unavailable ranges179second # [ 0.002497] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]180test # [ 0.018750] ACPI: PM-Timer IO Port: 0x608181second # [ 0.002497] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]182test # [ 0.018762] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])183second # [ 0.002498] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]184test # [ 0.018785] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23185second # [ 0.002498] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]186test # [ 0.018787] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)187second # [ 0.002499] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]188test # [ 0.018789] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)189second # [ 0.002499] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]190test # [ 0.018790] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)191second # [ 0.002499] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]192test # [ 0.018791] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)193second # [ 0.002862] No NUMA configuration found194test # [ 0.018792] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)195second # [ 0.002863] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]196test # [ 0.018797] ACPI: Using ACPI (MADT) for SMP configuration information197second # [ 0.002866] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]198test # [ 0.018799] ACPI: HPET id: 0x8086a201 base: 0xfed00000199second # [ 0.002937] Zone ranges:200test # [ 0.018804] TSC deadline timer available201second # [ 0.002938] DMA [mem 0x0000000000001000-0x0000000000ffffff]202test # [ 0.018810] CPU topo: Max. logical packages: 1203second # [ 0.002939] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]204test # [ 0.018812] CPU topo: Max. logical dies: 1205second # [ 0.002940] Normal empty206second # [ 0.002941] Device empty207test # [ 0.018812] CPU topo: Max. dies per package: 1208second # [ 0.002941] Movable zone start for each node209test # [ 0.018816] CPU topo: Max. threads per core: 1210second # [ 0.002942] Early memory node ranges211test # [ 0.018817] CPU topo: Num. cores per package: 1212second # [ 0.002942] node 0: [mem 0x0000000000001000-0x000000000009efff]213test # [ 0.018817] CPU topo: Num. threads per package: 1214second # [ 0.002943] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]215test # [ 0.018817] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs216second # [ 0.002944] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]217test # [ 0.018840] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()218second # [ 0.002964] On node 0, zone DMA: 1 pages in unavailable ranges219test # [ 0.018882] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]220second # [ 0.003170] On node 0, zone DMA: 97 pages in unavailable ranges221test # [ 0.018884] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]222second # [ 0.016661] On node 0, zone DMA32: 40 pages in unavailable ranges223second # [ 0.017489] ACPI: PM-Timer IO Port: 0x608224test # [ 0.018886] [mem 0x40000000-0xafffffff] available for PCI devices225test # [ 0.018888] Booting paravirtualized kernel on KVM226second # [ 0.017500] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])227second # [ 0.017521] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23228test # [ 0.018891] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns229second # [ 0.017523] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)230test # [ 0.022381] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1231second # [ 0.017524] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)232test # [ 0.024124] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152233second # [ 0.017525] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)234test # [ 0.024163] kvm-guest: PV spinlocks disabled, single CPU235second # [ 0.017526] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)236second # [ 0.017526] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)237second # [ 0.017528] ACPI: Using ACPI (MADT) for SMP configuration information238second # [ 0.017529] ACPI: HPET id: 0x8086a201 base: 0xfed00000239second # [ 0.017533] TSC deadline timer available240second # [ 0.017537] CPU topo: Max. logical packages: 1241test # [ 0.024165] 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/swxa86y3wmqx1dr86miw20bagz8rxc7l-nixos-system-test-test/init regInfo=/nix/store/xdi2dl0yv3lc6ql6cj4x29pb15jsldpa-closure-info/registration console=ttyS0,115200n8 console=tty0242second # [ 0.017538] CPU topo: Max. logical dies: 1243second # [ 0.017538] CPU topo: Max. dies per package: 1244second # [ 0.017541] CPU topo: Max. threads per core: 1245test # [ 0.024256] Unknown kernel command line parameters "regInfo=/nix/store/xdi2dl0yv3lc6ql6cj4x29pb15jsldpa-closure-info/registration", will be passed to user space.246second # [ 0.017542] CPU topo: Num. cores per package: 1247test # [ 0.024447] random: crng init done248second # [ 0.017542] CPU topo: Num. threads per package: 1249test # [ 0.024448] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes250second # [ 0.017542] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs251test # [ 0.025308] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)252second # [ 0.017559] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()253test # [ 0.025330] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)254second # [ 0.017591] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]255test # [ 0.025360] Fallback order for Node 0: 0256second # [ 0.017592] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]257test # [ 0.025363] Built 1 zonelists, mobility grouping on. Total pages: 262006258test # [ 0.025364] Policy zone: DMA32259second # [ 0.017593] [mem 0x40000000-0xafffffff] available for PCI devices260second # [ 0.017594] Booting paravirtualized kernel on KVM261test # [ 0.027508] mem auto-init: stack:all(zero), heap alloc:on, heap free:off262test # [ 0.030179] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1263second # [ 0.017597] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns264test # [ 0.031958] allocated 2097152 bytes of page_ext265test # [ 0.041184] ftrace: allocating 48732 entries in 192 pages266second # [ 0.021007] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1267test # [ 0.041185] ftrace: allocated 192 pages with 2 groups268second # [ 0.022779] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152269test # [ 0.041901] Dynamic Preempt: lazy270second # [ 0.022811] kvm-guest: PV spinlocks disabled, single CPU271test # [ 0.042039] rcu: Preemptible hierarchical RCU implementation.272test # [ 0.042039] rcu: RCU event tracing is enabled.273test # [ 0.042040] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.274test # [ 0.042041] Trampoline variant of Tasks RCU enabled.275test # [ 0.042042] Rude variant of Tasks RCU enabled.276test # [ 0.042042] Tracing variant of Tasks RCU enabled.277second # [ 0.022813] 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/41grk726jb1nqr68nw7vkjsf12lbnw7r-nixos-system-second-test/init regInfo=/nix/store/7h6vr552qvrsg66hhmv9f619mb9h95d8-closure-info/registration console=ttyS0,115200n8 console=tty0278test # [ 0.042043] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.279test # [ 0.042043] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1280second # [ 0.022889] Unknown kernel command line parameters "regInfo=/nix/store/7h6vr552qvrsg66hhmv9f619mb9h95d8-closure-info/registration", will be passed to user space.281second # [ 0.023081] random: crng init done282test # [ 0.042057] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.283second # [ 0.023081] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes284test # [ 0.042058] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.285second # [ 0.023892] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)286test # [ 0.042059] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.287second # [ 0.023904] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)288second # [ 0.023935] Fallback order for Node 0: 0289test # [ 0.045440] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16290second # [ 0.023938] Built 1 zonelists, mobility grouping on. Total pages: 262006291test # [ 0.045697] rcu: srcu_init: Setting srcu_struct sizes based on contention.292second # [ 0.023938] Policy zone: DMA32293second # [ 0.025783] mem auto-init: stack:all(zero), heap alloc:on, heap free:off294test # [ 0.045702] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns295second # [ 0.028135] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1296test # [ 0.045862] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)297second # [ 0.029809] allocated 2097152 bytes of page_ext298test # [ 0.051326] Console: colour VGA+ 80x25299second # [ 0.039194] ftrace: allocating 48732 entries in 192 pages300test # [ 0.051329] printk: legacy console [tty0] enabled301second # [ 0.039196] ftrace: allocated 192 pages with 2 groups302test # [ 0.083424] printk: legacy console [ttyS0] enabled303second # [ 0.039911] Dynamic Preempt: lazy304test # [ 0.224093] ACPI: Core revision 20250807305second # [ 0.040034] rcu: Preemptible hierarchical RCU implementation.306second # [ 0.040035] rcu: RCU event tracing is enabled.307second # [ 0.040035] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.308test # [ 0.225231] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns309second # [ 0.040036] Trampoline variant of Tasks RCU enabled.310test # [ 0.227331] APIC: Switch to symmetric I/O mode setup311second # [ 0.040037] Rude variant of Tasks RCU enabled.312test # [ 0.228674] x2apic enabled313second # [ 0.040037] Tracing variant of Tasks RCU enabled.314second # [ 0.040038] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.315test # [ 0.229652] APIC: Switched APIC routing to: physical x2apic316second # [ 0.040038] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1317test # [ 0.231941] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1318second # [ 0.040223] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.319second # [ 0.040224] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.320test # [ 0.233395] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3c6d4d0b6d3, max_idle_ns: 440795255641 ns321second # [ 0.040225] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.322test # [ 0.235597] Calibrating delay loop (skipped) preset value.. 8384.24 BogoMIPS (lpj=4192120)323second # [ 0.043626] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16324second # [ 0.043872] rcu: srcu_init: Setting srcu_struct sizes based on contention.325test # [ 0.236691] x86/cpu: User Mode Instruction Prevention (UMIP) activated326test # [ 0.237750] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127327second # [ 0.043877] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns328test # [ 0.239595] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0329second # [ 0.044042] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)330second # [ 0.049470] Console: colour VGA+ 80x25331second # [ 0.049474] printk: legacy console [tty0] enabled332test # [ 0.240602] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto333second # [ 0.082375] printk: legacy console [ttyS0] enabled334second # [ 0.224089] ACPI: Core revision 20250807335test # [ 0.242596] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl336second # [ 0.225199] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns337test # [ 0.244596] Spectre V2 : Mitigation: Enhanced / Automatic IBRS338second # [ 0.227265] APIC: Switch to symmetric I/O mode setup339second # [ 0.228519] x2apic enabled340test # [ 0.245596] Speculative Return Stack Overflow: Mitigation: Safe RET341second # [ 0.229400] APIC: Switched APIC routing to: physical x2apic342test # [ 0.246597] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization343second # [ 0.231555] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1344second # [ 0.232895] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3c6d4d0b6d3, max_idle_ns: 440795255641 ns345second # [ 0.235136] Calibrating delay loop (skipped) preset value.. 8384.24 BogoMIPS (lpj=4192120)346test # [ 0.247604] Spectre V2 : Enabling IBPB for BPF347second # [ 0.237225] x86/cpu: User Mode Instruction Prevention (UMIP) activated348test # [ 0.249601] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier349second # [ 0.238268] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127350test # [ 0.251596] active return thunk: srso_alias_return_thunk351second # [ 0.239134] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0352test # [ 0.252620] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'353second # [ 0.240137] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto354test # [ 0.254595] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'355second # [ 0.242134] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl356test # [ 0.255596] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'357second # [ 0.243134] Spectre V2 : Mitigation: Enhanced / Automatic IBRS358test # [ 0.257596] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'359second # [ 0.245134] Speculative Return Stack Overflow: Mitigation: Safe RET360test # [ 0.258595] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'361test # [ 0.259595] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'362second # [ 0.246134] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization363test # [ 0.261595] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'364test # [ 0.262596] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'365second # [ 0.247140] Spectre V2 : Enabling IBPB for BPF366test # [ 0.263598] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'367test # [ 0.265596] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256368second # [ 0.249136] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier369test # [ 0.266595] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64370second # [ 0.250135] active return thunk: srso_alias_return_thunk371test # [ 0.267596] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512372second # [ 0.251156] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'373test # [ 0.268596] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024374second # [ 0.252134] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'375test # [ 0.269595] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8376second # [ 0.254134] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'377test # [ 0.271595] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16378second # [ 0.255134] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'379test # [ 0.272595] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24380second # [ 0.256134] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'381test # [ 0.273595] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format.382second # [ 0.258134] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'383second # [ 0.259134] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'384second # [ 0.260134] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'385second # [ 0.261134] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'386second # [ 0.263134] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256387second # [ 0.264133] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64388second # [ 0.266133] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512389second # [ 0.267133] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024390second # [ 0.268133] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8391second # [ 0.270132] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16392second # [ 0.271133] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24393second # [ 0.272133] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format.394test # [ 0.304635] Freeing SMP alternatives memory: 44K395test # [ 0.305598] pid_max: default: 32768 minimum: 301396test # [ 0.307451] LSM: initializing lsm=capability,landlock,yama,bpf,ima397test # [ 0.308715] landlock: Up and running.398test # [ 0.309596] Yama: becoming mindful.399test # [ 0.310839] LSM support for eBPF active400test # [ 0.311714] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)401test # [ 0.312619] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)402test # [ 0.314528] smpboot: CPU0: AMD Ryzen 9 7950X3D 16-Core Processor (family: 0x19, model: 0x61, stepping: 0x2)403test # [ 0.315032] Performance Events: Fam17h+ core perfctr, AMD PMU driver.404second # [ 0.301084] Freeing SMP alternatives memory: 44K405test # [ 0.315600] ... version: 2406second # [ 0.301904] pid_max: default: 32768 minimum: 301407test # [ 0.316530] ... bit width: 48408second # [ 0.303181] LSM: initializing lsm=capability,landlock,yama,bpf,ima409test # [ 0.317338] ... generic counters: 6410second # [ 0.304224] landlock: Up and running.411test # [ 0.317607] ... generic bitmap: 000000000000003f412second # [ 0.305134] Yama: becoming mindful.413test # [ 0.318597] ... fixed-purpose counters: 0414second # [ 0.306333] LSM support for eBPF active415test # [ 0.319534] ... fixed-purpose bitmap: 0000000000000000416second # [ 0.307216] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)417test # [ 0.320551] ... value mask: 0000ffffffffffff418test # [ 0.321533] ... max period: 00007fffffffffff419second # [ 0.308152] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)420test # [ 0.322524] ... global_ctrl mask: 000000000000003f421test # [ 0.323639] signal: max sigframe size: 3376422second # [ 0.311443] smpboot: CPU0: AMD Ryzen 9 7950X3D 16-Core Processor (family: 0x19, model: 0x61, stepping: 0x2)423test # [ 0.324641] rcu: Hierarchical SRCU implementation.424test # [ 0.325604] rcu: Max phase no-delay instances is 400.425second # [ 0.312584] Performance Events: Fam17h+ core perfctr, AMD PMU driver.426second # [ 0.313142] ... version: 2427second # [ 0.314096] ... bit width: 48428second # [ 0.314893] ... generic counters: 6429test # [ 0.329796] smp: Bringing up secondary CPUs ...430second # [ 0.315135] ... generic bitmap: 000000000000003f431test # [ 0.330616] smp: Brought up 1 node, 1 CPU432second # [ 0.316136] ... fixed-purpose counters: 0433test # [ 0.331546] smpboot: Total of 1 processors activated (8384.24 BogoMIPS)434second # [ 0.317092] ... fixed-purpose bitmap: 0000000000000000435second # [ 0.318089] ... value mask: 0000ffffffffffff436second # [ 0.319102] ... max period: 00007fffffffffff437test # [ 0.332740] Memory: 943032K/1048024K available (17215K kernel code, 2726K rwdata, 13584K rodata, 3644K init, 2988K bss, 97584K reserved, 0K cma-reserved)438test # [ 0.333813] devtmpfs: initialized439second # [ 0.320098] ... global_ctrl mask: 000000000000003f440second # [ 0.321198] signal: max sigframe size: 3376441test # [ 0.334779] x86/mm: Memory block size: 128MB442second # [ 0.322243] rcu: Hierarchical SRCU implementation.443second # [ 0.323140] rcu: Max phase no-delay instances is 400.444test # [ 0.336357] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)445test # [ 0.337624] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).446test # [ 0.338682] pinctrl core: initialized pinctrl subsystem447second # [ 0.327361] smp: Bringing up secondary CPUs ...448test # [ 0.339848] PM: RTC time: 20:04:52, date: 2026-09-04449second # [ 0.328148] smp: Brought up 1 node, 1 CPU450second # [ 0.329050] smpboot: Total of 1 processors activated (8384.24 BogoMIPS)451test # [ 0.342680] NET: Registered PF_NETLINK/PF_ROUTE protocol family452test # [ 0.343871] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations453second # [ 0.330308] Memory: 943020K/1048024K available (17215K kernel code, 2726K rwdata, 13584K rodata, 3644K init, 2988K bss, 97584K reserved, 0K cma-reserved)454second # [ 0.331337] devtmpfs: initialized455test # [ 0.344613] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations456second # [ 0.332294] x86/mm: Memory block size: 128MB457test # [ 0.345709] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations458test # [ 0.346604] audit: initializing netlink subsys (disabled)459second # [ 0.333919] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)460test # [ 0.347784] thermal_sys: Registered thermal governor 'fair_share'461second # [ 0.335161] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).462test # [ 0.347785] thermal_sys: Registered thermal governor 'bang_bang'463second # [ 0.336203] pinctrl core: initialized pinctrl subsystem464test # [ 0.348598] thermal_sys: Registered thermal governor 'step_wise'465second # [ 0.337407] PM: RTC time: 20:04:52, date: 2026-09-04466test # [ 0.349600] audit: type=2000 audit(1788552293.169:1): state=initialized audit_enabled=0 res=1467test # [ 0.351599] thermal_sys: Registered thermal governor 'user_space'468second # [ 0.340182] NET: Registered PF_NETLINK/PF_ROUTE protocol family469test # [ 0.351600] thermal_sys: Registered thermal governor 'power_allocator'470test # [ 0.352611] cpuidle: using governor menu471second # [ 0.341424] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations472second # [ 0.342152] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations473test # [ 0.355327] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5474second # [ 0.343243] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations475test # [ 0.355820] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]476second # [ 0.344143] audit: initializing netlink subsys (disabled)477test # [ 0.356599] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry478second # [ 0.345349] thermal_sys: Registered thermal governor 'fair_share'479test # [ 0.357607] PCI: Using configuration type 1 for base access480second # [ 0.345350] thermal_sys: Registered thermal governor 'bang_bang'481test # [ 0.358766] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.482second # [ 0.346135] thermal_sys: Registered thermal governor 'step_wise'483second # [ 0.347137] audit: type=2000 audit(1788552293.187:1): state=initialized audit_enabled=0 res=1484second # [ 0.349137] thermal_sys: Registered thermal governor 'user_space'485second # [ 0.349138] thermal_sys: Registered thermal governor 'power_allocator'486second # [ 0.350148] cpuidle: using governor menu487second # [ 0.352850] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5488test # [ 0.365809] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages489test # [ 0.366597] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page490second # [ 0.353368] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]491second # [ 0.354138] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry492second # [ 0.355147] PCI: Using configuration type 1 for base access493second # [ 0.356308] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.494test # [ 0.371597] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages495test # [ 0.372598] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page496second # [ 0.361335] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages497second # [ 0.362135] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page498test # [ 0.380046] ACPI: Added _OSI(Module Device)499test # [ 0.380598] ACPI: Added _OSI(Processor Device)500test # [ 0.381560] ACPI: Added _OSI(Processor Aggregator Device)501second # [ 0.367135] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages502second # [ 0.368135] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page503test # [ 0.386555] ACPI: 1 ACPI AML tables successfully acquired and loaded504test # [ 0.392718] ACPI: Interpreter enabled505second # [ 0.378210] ACPI: Added _OSI(Module Device)506test # [ 0.393616] ACPI: PM: (supports S0 S3 S4 S5)507second # [ 0.379135] ACPI: Added _OSI(Processor Device)508second # [ 0.380119] ACPI: Added _OSI(Processor Aggregator Device)509test # [ 0.396598] ACPI: Using IOAPIC for interrupt routing510test # [ 0.397670] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug511test # [ 0.399217] PCI: Using E820 reservations for host bridge windows512test # [ 0.399733] ACPI: Enabled 2 GPEs in block 00 to 3F513second # [ 0.389245] ACPI: 1 ACPI AML tables successfully acquired and loaded514second # [ 0.391026] ACPI: Interpreter enabled515test # [ 0.404232] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])516second # [ 0.391821] ACPI: PM: (supports S0 S3 S4 S5)517test # [ 0.404609] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]518second # [ 0.392137] ACPI: Using IOAPIC for interrupt routing519test # [ 0.405680] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]520test # [ 0.406699] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]521second # [ 0.395191] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug522test # [ 0.408087] PCI host bridge to bus 0000:00523second # [ 0.396136] PCI: Using E820 reservations for host bridge windows524test # [ 0.408605] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]525second # [ 0.397289] ACPI: Enabled 2 GPEs in block 00 to 3F526test # [ 0.409599] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]527test # [ 0.410599] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]528test # [ 0.411598] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]529second # [ 0.401744] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])530test # [ 0.412598] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]531second # [ 0.402142] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]532test # [ 0.413598] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]533second # [ 0.403197] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]534test # [ 0.414600] pci_bus 0000:00: root bus resource [bus 00-ff]535second # [ 0.404233] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]536test # [ 0.415726] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint537second # [ 0.405557] PCI host bridge to bus 0000:00538second # [ 0.406141] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]539test # [ 0.417352] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint540second # [ 0.407137] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]541second # [ 0.408136] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]542second # [ 0.409136] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]543test # [ 0.420643] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]544second # [ 0.410136] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]545test # [ 0.421620] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]546second # [ 0.411136] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]547test # [ 0.422714] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]548second # [ 0.412138] pci_bus 0000:00: root bus resource [bus 00-ff]549test # [ 0.423952] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]550second # [ 0.413299] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint551test # [ 0.425455] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint552second # [ 0.414868] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint553test # [ 0.428449] pci 0000:00:02.0: BAR 0 [io 0xc140-0xc15f]554test # [ 0.429493] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]555second # [ 0.418158] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]556test # [ 0.430641] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]557second # [ 0.419158] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]558test # [ 0.431608] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]559second # [ 0.420178] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]560second # [ 0.421308] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]561test # [ 0.433561] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint562second # [ 0.422929] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint563test # [ 0.435608] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f]564test # [ 0.436608] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]565second # [ 0.425147] pci 0000:00:02.0: BAR 0 [io 0xc140-0xc15f]566test # [ 0.437639] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]567second # [ 0.426149] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]568second # [ 0.427178] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]569test # [ 0.439550] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint570second # [ 0.428147] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]571test # [ 0.442620] pci 0000:00:04.0: BAR 0 [io 0xc080-0xc0bf]572second # [ 0.430064] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint573test # [ 0.443614] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]574test # [ 0.444645] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]575second # [ 0.432149] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f]576second # [ 0.433148] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]577test # [ 0.446590] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint578second # [ 0.434178] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]579second # [ 0.436122] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint580test # [ 0.449427] pci 0000:00:05.0: BAR 0 [io 0xc180-0xc19f]581test # [ 0.450611] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]582test # [ 0.451645] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]583second # [ 0.439151] pci 0000:00:04.0: BAR 0 [io 0xc080-0xc0bf]584second # [ 0.440147] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]585test # [ 0.453608] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint586second # [ 0.441178] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]587test # [ 0.455608] pci 0000:00:06.0: BAR 0 [io 0xc1a0-0xc1bf]588second # [ 0.443106] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint589test # [ 0.456610] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]590test # [ 0.457641] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]591second # [ 0.446136] pci 0000:00:05.0: BAR 0 [io 0xc180-0xc19f]592second # [ 0.447150] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]593test # [ 0.459685] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint594second # [ 0.448185] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]595test # [ 0.462192] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f]596second # [ 0.450335] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint597test # [ 0.462610] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]598test # [ 0.463641] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]599second # [ 0.452160] pci 0000:00:06.0: BAR 0 [io 0xc1a0-0xc1bf]600second # [ 0.453149] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]601test # [ 0.466069] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint602second # [ 0.454201] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]603test # [ 0.467610] pci 0000:00:08.0: BAR 0 [io 0xc1c0-0xc1df]604second # [ 0.456120] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint605test # [ 0.468610] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]606test # [ 0.469641] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]607second # [ 0.458940] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f]608test # [ 0.470610] pci 0000:00:08.0: ROM [mem 0xfeb80000-0xfebbffff pref]609second # [ 0.460149] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]610test # [ 0.473042] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint611second # [ 0.461183] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]612second # [ 0.463186] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint613test # [ 0.474624] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]614test # [ 0.475637] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]615second # [ 0.465159] pci 0000:00:08.0: BAR 0 [io 0xc1c0-0xc1df]616test # [ 0.477535] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint617second # [ 0.466148] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]618second # [ 0.467177] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]619test # [ 0.479973] pci 0000:00:0a.0: BAR 0 [io 0xc0c0-0xc0ff]620second # [ 0.468147] pci 0000:00:08.0: ROM [mem 0xfeb80000-0xfebbffff pref]621test # [ 0.480611] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]622test # [ 0.481644] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]623second # [ 0.470104] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint624test # [ 0.483549] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint625second # [ 0.472155] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]626second # [ 0.473175] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]627test # [ 0.485608] pci 0000:00:0b.0: BAR 0 [io 0xc1e0-0xc1ff]628test # [ 0.486610] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]629second # [ 0.475068] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint630test # [ 0.487639] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]631second # [ 0.478026] pci 0000:00:0a.0: BAR 0 [io 0xc0c0-0xc0ff]632second # [ 0.479108] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]633second # [ 0.480179] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]634second # [ 0.482178] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint635second # [ 0.484885] pci 0000:00:0b.0: BAR 0 [io 0xc1e0-0xc1ff]636second # [ 0.486078] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]637test # [ 0.489598] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint638second # [ 0.487154] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]639test # [ 0.492648] pci 0000:00:1d.0: BAR 4 [io 0xc200-0xc21f]640test # [ 0.493917] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint641test # [ 0.495597] pci 0000:00:1d.1: BAR 4 [io 0xc220-0xc23f]642test # [ 0.496882] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint643test # [ 0.498617] pci 0000:00:1d.2: BAR 4 [io 0xc240-0xc25f]644second # [ 0.489094] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint645test # [ 0.499931] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint646second # [ 0.491175] pci 0000:00:1d.0: BAR 4 [io 0xc200-0xc21f]647test # [ 0.501386] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]648second # [ 0.492432] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint649test # [ 0.502955] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint650second # [ 0.494155] pci 0000:00:1d.1: BAR 4 [io 0xc220-0xc23f]651test # [ 0.504087] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO652second # [ 0.495456] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint653test # [ 0.504953] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint654second # [ 0.497185] pci 0000:00:1d.2: BAR 4 [io 0xc240-0xc25f]655test # [ 0.506655] pci 0000:00:1f.2: BAR 4 [io 0xc260-0xc27f]656second # [ 0.498500] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint657test # [ 0.507611] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]658second # [ 0.500076] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]659test # [ 0.509222] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint660second # [ 0.501569] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint661test # [ 0.511653] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]662second # [ 0.502703] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO663second # [ 0.503525] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint664second # [ 0.505192] pci 0000:00:1f.2: BAR 4 [io 0xc260-0xc27f]665test # [ 0.517481] ACPI: PCI: Interrupt link LNKA configured for IRQ 10666second # [ 0.506149] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]667test # [ 0.518727] ACPI: PCI: Interrupt link LNKB configured for IRQ 10668test # [ 0.519715] ACPI: PCI: Interrupt link LNKC configured for IRQ 11669second # [ 0.507666] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint670test # [ 0.520719] ACPI: PCI: Interrupt link LNKD configured for IRQ 11671second # [ 0.509174] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]672test # [ 0.521726] ACPI: PCI: Interrupt link LNKE configured for IRQ 10673test # [ 0.522713] ACPI: PCI: Interrupt link LNKF configured for IRQ 10674test # [ 0.523715] ACPI: PCI: Interrupt link LNKG configured for IRQ 11675test # [ 0.524715] ACPI: PCI: Interrupt link LNKH configured for IRQ 11676test # [ 0.525645] ACPI: PCI: Interrupt link GSIA configured for IRQ 16677second # [ 0.515336] ACPI: PCI: Interrupt link LNKA configured for IRQ 10678test # [ 0.526624] ACPI: PCI: Interrupt link GSIB configured for IRQ 17679second # [ 0.516259] ACPI: PCI: Interrupt link LNKB configured for IRQ 10680test # [ 0.527652] ACPI: PCI: Interrupt link GSIC configured for IRQ 18681second # [ 0.517318] ACPI: PCI: Interrupt link LNKC configured for IRQ 11682test # [ 0.528611] ACPI: PCI: Interrupt link GSID configured for IRQ 19683test # [ 0.529610] ACPI: PCI: Interrupt link GSIE configured for IRQ 20684test # [ 0.530611] ACPI: PCI: Interrupt link GSIF configured for IRQ 21685second # [ 0.518416] ACPI: PCI: Interrupt link LNKD configured for IRQ 11686test # [ 0.531611] ACPI: PCI: Interrupt link GSIG configured for IRQ 22687test # [ 0.532614] ACPI: PCI: Interrupt link GSIH configured for IRQ 23688second # [ 0.519279] ACPI: PCI: Interrupt link LNKE configured for IRQ 10689second # [ 0.520271] ACPI: PCI: Interrupt link LNKF configured for IRQ 10690test # [ 0.534505] iommu: Default domain type: Translated691test # [ 0.535420] iommu: DMA domain TLB invalidation policy: lazy mode692second # [ 0.521277] ACPI: PCI: Interrupt link LNKG configured for IRQ 11693test # [ 0.536827] ACPI: bus type USB registered694second # [ 0.522273] ACPI: PCI: Interrupt link LNKH configured for IRQ 11695test # [ 0.537672] usbcore: registered new interface driver usbfs696second # [ 0.523192] ACPI: PCI: Interrupt link GSIA configured for IRQ 16697test # [ 0.538621] usbcore: registered new interface driver hub698second # [ 0.524157] ACPI: PCI: Interrupt link GSIB configured for IRQ 17699test # [ 0.539623] usbcore: registered new device driver usb700second # [ 0.525157] ACPI: PCI: Interrupt link GSIC configured for IRQ 18701test # [ 0.541418] NetLabel: Initializing702second # [ 0.526158] ACPI: PCI: Interrupt link GSID configured for IRQ 19703test # [ 0.542222] NetLabel: domain hash size = 128704test # [ 0.542597] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO705second # [ 0.527153] ACPI: PCI: Interrupt link GSIE configured for IRQ 20706test # [ 0.543653] NetLabel: unlabeled traffic allowed by default707second # [ 0.528153] ACPI: PCI: Interrupt link GSIF configured for IRQ 21708test # [ 0.544609] PCI: Using ACPI for IRQ routing709second # [ 0.529157] ACPI: PCI: Interrupt link GSIG configured for IRQ 22710second # [ 0.530174] ACPI: PCI: Interrupt link GSIH configured for IRQ 23711second # [ 0.532309] iommu: Default domain type: Translated712second # [ 0.533145] iommu: DMA domain TLB invalidation policy: lazy mode713second # [ 0.534432] ACPI: bus type USB registered714second # [ 0.535216] usbcore: registered new interface driver usbfs715second # [ 0.536156] usbcore: registered new interface driver hub716second # [ 0.537164] usbcore: registered new device driver usb717second # [ 0.539008] NetLabel: Initializing718second # [ 0.539786] NetLabel: domain hash size = 128719second # [ 0.540135] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO720second # [ 0.541188] NetLabel: unlabeled traffic allowed by default721second # [ 0.542160] PCI: Using ACPI for IRQ routing722test # [ 0.623519] pci 0000:00:01.0: vgaarb: setting as boot VGA device723test # [ 0.623593] pci 0000:00:01.0: vgaarb: bridge control possible724test # [ 0.623593] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none725test # [ 0.623601] vgaarb: loaded726test # [ 0.624409] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0727test # [ 0.625398] hpet0: 3 comparators, 64-bit 100.000000 MHz counter728test # [ 0.630684] clocksource: Switched to clocksource kvm-clock729test # [ 0.632446] VFS: Disk quotas dquot_6.6.0730test # [ 0.634694] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)731test # [ 0.636267] pnp: PnP ACPI init732second # [ 0.620973] pci 0000:00:01.0: vgaarb: setting as boot VGA device733test # [ 0.637172] ACPI: IRQ 4 override to edge(!), high(!)734second # [ 0.621131] pci 0000:00:01.0: vgaarb: bridge control possible735test # [ 0.638330] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved736second # [ 0.621131] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none737second # [ 0.621147] vgaarb: loaded738test # [ 0.640171] pnp: PnP ACPI: found 6 devices739second # [ 0.621956] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0740second # [ 0.622954] hpet0: 3 comparators, 64-bit 100.000000 MHz counter741second # [ 0.627238] clocksource: Switched to clocksource kvm-clock742test # [ 0.647350] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns743second # [ 0.629054] VFS: Disk quotas dquot_6.6.0744test # [ 0.649168] clocksource: Switched to clocksource acpi_pm745test # [ 0.650388] NET: Registered PF_INET protocol family746second # [ 0.631403] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)747test # [ 0.651610] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)748second # [ 0.633054] pnp: PnP ACPI init749second # [ 0.633984] ACPI: IRQ 4 override to edge(!), high(!)750second # [ 0.635163] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved751second # [ 0.637012] pnp: PnP ACPI: found 6 devices752second # [ 0.644199] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns753test # [ 0.665347] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)754second # [ 0.646113] clocksource: Switched to clocksource acpi_pm755second # [ 0.647407] NET: Registered PF_INET protocol family756test # [ 0.667208] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)757second # [ 0.648671] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)758test # [ 0.668826] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)759test # [ 0.670528] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)760test # [ 0.672090] TCP: Hash tables configured (established 8192 bind 8192)761test # [ 0.673525] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)762test # [ 0.675180] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)763test # [ 0.676601] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)764test # [ 0.678169] NET: Registered PF_UNIX/PF_LOCAL protocol family765test # [ 0.679440] NET: Registered PF_XDP protocol family766test # [ 0.680525] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]767test # [ 0.681853] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]768second # [ 0.662546] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)769test # [ 0.683197] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]770test # [ 0.684618] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]771second # [ 0.664542] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)772test # [ 0.686067] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]773second # [ 0.666358] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)774test # [ 0.687489] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]775second # [ 0.668141] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)776test # [ 0.689511] ACPI: \_SB_.GSIA: Enabled at IRQ 16777second # [ 0.669794] TCP: Hash tables configured (established 8192 bind 8192)778test # [ 0.691620] ACPI: \_SB_.GSIB: Enabled at IRQ 17779second # [ 0.671339] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)780test # [ 0.693625] ACPI: \_SB_.GSIC: Enabled at IRQ 18781second # [ 0.673075] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)782second # [ 0.674668] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)783test # [ 0.695603] ACPI: \_SB_.GSID: Enabled at IRQ 19784second # [ 0.676213] NET: Registered PF_UNIX/PF_LOCAL protocol family785test # [ 0.697348] PCI: CLS 0 bytes, default 64786second # [ 0.677577] NET: Registered PF_XDP protocol family787second # [ 0.678717] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]788test # [ 0.698460] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3c6d4d0b6d3, max_idle_ns: 440795255641 ns789second # [ 0.680133] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]790test # [ 0.700593] Trying to unpack rootfs image as initramfs...791second # [ 0.681564] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]792second # [ 0.683030] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]793second # [ 0.684592] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]794second # [ 0.686198] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]795second # [ 0.688330] ACPI: \_SB_.GSIA: Enabled at IRQ 16796second # [ 0.690505] ACPI: \_SB_.GSIB: Enabled at IRQ 17797second # [ 0.692554] ACPI: \_SB_.GSIC: Enabled at IRQ 18798second # [ 0.694634] ACPI: \_SB_.GSID: Enabled at IRQ 19799second # [ 0.696497] PCI: CLS 0 bytes, default 64800second # [ 0.697603] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3c6d4d0b6d3, max_idle_ns: 440795255641 ns801second # [ 0.699855] Trying to unpack rootfs image as initramfs...802test # [ 0.741495] Initialise system trusted keyrings803test # [ 0.745994] workingset: timestamp_bits=40 max_order=18 bucket_order=0804second # [ 0.740612] Initialise system trusted keyrings805second # [ 0.741827] workingset: timestamp_bits=40 max_order=18 bucket_order=0806test # [ 0.765193] Key type asymmetric registered807test # [ 0.766151] Asymmetric key parser 'x509' registered808test # [ 0.769979] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)809test # [ 0.773998] io scheduler mq-deadline registered810test # [ 0.775015] io scheduler kyber registered811test # [ 0.778456] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled812second # [ 0.761370] Key type asymmetric registered813second # [ 0.762310] Asymmetric key parser 'x509' registered814test # [ 0.783302] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A815second # [ 0.767333] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)816second # [ 0.769040] io scheduler mq-deadline registered817test # [ 0.789404] Linux agpgart interface v0.103818test # [ 0.790441] ACPI: bus type drm_connector registered819second # [ 0.772306] io scheduler kyber registered820test # [ 0.793350] usbcore: registered new interface driver usbserial_generic821second # [ 0.773756] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled822test # [ 0.794814] usbserial: USB Serial support registered for generic823second # [ 0.777426] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A824test # [ 0.798947] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.825test # [ 0.798947] Please enable it if your BIOS has the CPPC option.826second # [ 0.782805] Linux agpgart interface v0.103827second # [ 0.783875] ACPI: bus type drm_connector registered828second # [ 0.786831] usbcore: registered new interface driver usbserial_generic829test # [ 0.807938] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled830second # [ 0.788318] usbserial: USB Serial support registered for generic831test # [ 0.809674] drop_monitor: Initializing network drop monitor service832test # [ 0.813054] NET: Registered PF_INET6 protocol family833second # [ 0.793311] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.834second # [ 0.793311] Please enable it if your BIOS has the CPPC option.835test # [ 0.817169] Segment Routing with IPv6836second # [ 0.796210] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled837test # [ 0.818044] In-situ OAM (IOAM) with IPv6838test # [ 0.821260] IPI shorthand broadcast: enabled839second # [ 0.801421] drop_monitor: Initializing network drop monitor service840second # [ 0.802869] NET: Registered PF_INET6 protocol family841test # [ 0.826816] sched_clock: Marking stable (641021397, 184891813)->(929259017, -103345807)842second # [ 0.807612] Segment Routing with IPv6843second # [ 0.808566] In-situ OAM (IOAM) with IPv6844second # [ 0.811648] IPI shorthand broadcast: enabled845test # [ 0.832170] registered taskstats version 1846test # [ 0.833327] Loading compiled-in X.509 certificates847second # [ 0.819772] sched_clock: Marking stable (634023283, 185254590)->(922391512, -103113639)848second # [ 0.825399] registered taskstats version 1849second # [ 0.826522] Loading compiled-in X.509 certificates850test # [ 0.852312] Demotion targets for Node 0: null851test # [ 0.853476] Key type .fscrypt registered852test # [ 0.855939] Key type fscrypt-provisioning registered853test # [ 0.857106] ima: No TPM chip found, activating TPM-bypass!854test # [ 0.859931] ima: Allocated hash algorithm: sha1855test # [ 0.860957] ima: No architecture policies found856second # [ 0.841522] Demotion targets for Node 0: null857second # [ 0.845356] Key type .fscrypt registered858test # [ 0.865933] PM: Magic number: 10:952:92859second # [ 0.846331] Key type fscrypt-provisioning registered860test # [ 0.866855] container: hash matches861second # [ 0.849392] ima: No TPM chip found, activating TPM-bypass!862test # [ 0.870480] RAS: Correctable Errors collector initialized.863second # [ 0.850631] ima: Allocated hash algorithm: sha1864second # [ 0.853310] ima: No architecture policies found865second # [ 0.856298] PM: Magic number: 10:952:92866second # [ 0.857227] container: hash matches867test # [ 0.878639] clk: Disabling unused clocks868test # [ 0.879551] PM: genpd: Disabling unused power domains869second # [ 0.860824] RAS: Correctable Errors collector initialized.870second # [ 0.869073] clk: Disabling unused clocks871second # [ 0.870021] PM: genpd: Disabling unused power domains872test # [ 1.005125] Freeing initrd memory: 29080K873test # [ 1.008058] Freeing unused decrypted memory: 2028K874test # [ 1.011812] Freeing unused kernel image (initmem) memory: 3644K875test # [ 1.013238] Write protecting the kernel read-only data: 32768k876test # [ 1.015252] Freeing unused kernel image (text/rodata gap) memory: 1216K877test # [ 1.017041] Freeing unused kernel image (rodata/data gap) memory: 752K878second # [ 1.011888] Freeing initrd memory: 29076K879second # [ 1.014819] Freeing unused decrypted memory: 2028K880second # [ 1.017485] Freeing unused kernel image (initmem) memory: 3644K881second # [ 1.018833] Write protecting the kernel read-only data: 32768k882second # [ 1.020861] Freeing unused kernel image (text/rodata gap) memory: 1216K883second # [ 1.022641] Freeing unused kernel image (rodata/data gap) memory: 752K884test # [ 1.051542] x86/mm: Checked W+X mappings: passed, no W+X pages found.885test # [ 1.052958] Run /init as init process886test # [ 1.061920] systemd[1]: Inserted module 'autofs4'887test # [ 1.075640] fuse: init (API version 7.45)888second # [ 1.056695] x86/mm: Checked W+X mappings: passed, no W+X pages found.889second # [ 1.058075] Run /init as init process890test # [ 1.080772] ACPI: \_SB_.GSIG: Enabled at IRQ 22891test # [ 1.083510] ACPI: \_SB_.GSIH: Enabled at IRQ 23892second # [ 1.066581] systemd[1]: Inserted module 'autofs4'893test # [ 1.086969] ACPI: \_SB_.GSIE: Enabled at IRQ 20894test # [ 1.089568] ACPI: \_SB_.GSIF: Enabled at IRQ 21895second # [ 1.079544] fuse: init (API version 7.45)896second # [ 1.084599] ACPI: \_SB_.GSIG: Enabled at IRQ 22897second # [ 1.087354] ACPI: \_SB_.GSIH: Enabled at IRQ 23898second # [ 1.090598] ACPI: \_SB_.GSIE: Enabled at IRQ 20899second # [ 1.093133] ACPI: \_SB_.GSIF: Enabled at IRQ 21900test # [ 1.117366] systemd[1]: Successfully made /usr/ read-only.901second # [ 1.119138] systemd[1]: Successfully made /usr/ read-only.902test # [ 1.453459] 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)903test # [ 1.459565] systemd[1]: Detected virtualization kvm.904test # [ 1.460693] systemd[1]: Detected architecture x86-64.905test # [ 1.461800] systemd[1]: Running in initrd.906test # [ 1.463127] systemd[1]: Initializing machine ID from random generator.907test # [ 1.464603] systemd[1]: Hostname set to <test>.908second # [ 1.455373] 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)909second # [ 1.461433] systemd[1]: Detected virtualization kvm.910second # [ 1.462547] systemd[1]: Detected architecture x86-64.911second # [ 1.463656] systemd[1]: Running in initrd.912second # [ 1.464852] systemd[1]: Initializing machine ID from random generator.913second # [ 1.466312] systemd[1]: Hostname set to <second>.914test # [ 1.646805] systemd[1]: bpf-restrict-fs: LSM BPF program attached915second # [ 1.647654] systemd[1]: bpf-restrict-fs: LSM BPF program attached916test # [ 1.678406] systemd[1]: Queued start job for default target Initrd Default Target.917test # [ 1.683105] systemd[1]: Created slice Slice /system/modprobe.918test # [ 1.684599] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.919test # [ 1.687080] systemd[1]: Expecting device /dev/disk/by-label/nixos...920test # [ 1.688523] systemd[1]: Reached target Path Units.921test # [ 1.689669] systemd[1]: Reached target Slice Units.922test # [ 1.690835] systemd[1]: Reached target Swaps.923test # [ 1.691936] systemd[1]: Reached target Timer Units.924test # [ 1.693264] systemd[1]: Listening on D-Bus System Message Bus Socket.925test # [ 1.694808] systemd[1]: Listening on Journal Socket (/dev/log).926test # [ 1.696421] systemd[1]: Listening on Journal Sockets.927test # [ 1.697754] systemd[1]: Listening on udev Control Socket.928second # [ 1.677992] systemd[1]: Queued start job for default target Initrd Default Target.929test # [ 1.699220] systemd[1]: Listening on udev Kernel Socket.930second # [ 1.682487] systemd[1]: Created slice Slice /system/modprobe.931second # [ 1.683962] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.932second # [ 1.685743] systemd[1]: Expecting device /dev/disk/by-label/nixos...933second # [ 1.687139] systemd[1]: Reached target Path Units.934second # [ 1.688274] systemd[1]: Reached target Slice Units.935test # [ 1.700513] systemd[1]: Reached target Socket Units.936second # [ 1.689398] systemd[1]: Reached target Swaps.937second # [ 1.690532] systemd[1]: Reached target Timer Units.938test # [ 1.711779] systemd[1]: Starting Create List of Static Device Nodes...939second # [ 1.691785] systemd[1]: Listening on D-Bus System Message Bus Socket.940second # [ 1.693448] systemd[1]: Listening on Journal Socket (/dev/log).941second # [ 1.695628] systemd[1]: Listening on Journal Sockets.942test # [ 1.716633] systemd[1]: Starting Load Kernel Module 9pnet_virtio...943second # [ 1.696958] systemd[1]: Listening on udev Control Socket.944second # [ 1.698405] systemd[1]: Listening on udev Kernel Socket.945test # [ 1.724999] systemd[1]: Starting Load Kernel Module configfs...946second # [ 1.699699] systemd[1]: Reached target Socket Units.947second # [ 1.710354] systemd[1]: Starting Create List of Static Device Nodes...948second # [ 1.715120] systemd[1]: Starting Load Kernel Module 9pnet_virtio...949test # [ 1.736117] systemd[1]: Starting Journal Service...950second # [ 1.723371] systemd[1]: Starting Load Kernel Module configfs...951test # [ 1.748971] systemd[1]: Starting Load Kernel Modules...952second # [ 1.733926] systemd[1]: Starting Journal Service...953test # [ 1.755639] netfs: FS-Cache loaded954test # [ 1.759050] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os955second # [ 1.746744] systemd[1]: Starting Load Kernel Modules...956test # [ 1.769393] systemd[1]: Starting Coldplug All udev Devices...957test # [ 1.772316] 9pnet: Installing 9P2000 support958second # [ 1.753171] netfs: FS-Cache loaded959second # [ 1.754646] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os960test # [ 1.781268] systemd-journald[67]: Collecting audit messages is disabled.961second # [ 1.763793] systemd[1]: Starting Coldplug All udev Devices...962second # [ 1.767994] 9pnet: Installing 9P2000 support963test # [ 1.790398] systemd[1]: Finished Create List of Static Device Nodes.964test # [ 1.798765] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.965second # [ 1.782959] systemd[1]: Finished Create List of Static Device Nodes.966test # [ 1.809021] systemd[1]: Finished Load Kernel Module 9pnet_virtio.967second # [ 1.789538] systemd-journald[67]: Collecting audit messages is disabled.968second # [ 1.792997] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.969test # [ 1.814516] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.970test # [ 1.819405] systemd[1]: modprobe@configfs.service: Deactivated successfully.971second # [ 1.802864] systemd[1]: Finished Load Kernel Module 9pnet_virtio.972test # [ 1.825451] systemd[1]: Finished Load Kernel Module configfs.973second # [ 1.807608] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.974second # [ 1.811831] systemd[1]: modprobe@configfs.service: Deactivated successfully.975test # [ 1.831607] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config976test # [ 1.834165] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev977second # [ 1.818806] systemd[1]: Finished Load Kernel Module configfs.978second # [ 1.822403] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev979second # [ 1.824991] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config980test # [ 1.846991] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...981second # [ 1.839374] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...982test # [ 1.863484] systemd[1]: Finished Load Kernel Modules.983test # [ 1.873102] systemd[1]: Starting Apply Kernel Variables...984second # [ 1.857368] systemd[1]: Finished Load Kernel Modules.985test # [ 1.889191] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.986second # [ 1.870362] systemd[1]: Starting Apply Kernel Variables...987second # [ 1.881753] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.988test # [ 1.902120] systemd[1]: Starting Create Static Device Nodes in /dev...989second # [ 1.893542] systemd[1]: Starting Create Static Device Nodes in /dev...990test # [ 1.918043] systemd[1]: Finished Apply Kernel Variables.991second # [ 1.909705] systemd[1]: Finished Apply Kernel Variables.992test # [ 1.938007] systemd[1]: Finished Create Static Device Nodes in /dev.993test # [ 1.756305] systemd-modules-load[68]: Inserted module 'dm_mod'994test # [ 1.759101] systemd-modules-load[68]: Inserted module 'virtio_balloon'995test # [ 1.760472] systemd-modules-load[68]: Inserted module 'virtio_gpu'996test # [ 1.946866] systemd[1]: Started Journal Service.997second # [ 1.930032] systemd[1]: Finished Create Static Device Nodes in /dev.998second # [ 1.935607] systemd[1]: Reached target Preparation for Local File Systems.999test # [ 1.773101] systemd[1]: Reached target Preparation for Local File Systems.1000test # [ 1.774552] systemd[1]: Reached target Local File Systems.1001second # [ 1.754446] systemd-modules-load[68]: Inserted module 'dm_mod'[ 1.942383] systemd[1]: Reached target Local File Systems.1002second # 1003test # [ 1.778080] systemd[1]: Starting Create System Files and Directories...1004second # [ 1.758427] systemd-modules-load[68]: Inserted module 'virtio_balloon'1005second # [ 1.759959] systemd-modules-load[68]: Inserted module 'virtio_gpu'1006test # [ 1.783468] systemd[1]: Starting Rule-based Manager for Device Events and Files...1007second # [ 1.950482] systemd[1]: Starting Rule-based Manager for Device Events and Files...1008second # [ 1.957505] systemd[1]: Started Journal Service.1009second # [ 1.790328] systemd[1]: Starting Create System Files and Directories...1010test # [ 1.813521] systemd[1]: Finished Create System Files and Directories.1011test # [ 1.831468] systemd-udevd[83]: Using default interface naming scheme 'v261'.1012second # [ 1.818312] systemd-udevd[75]: Using default interface naming scheme 'v261'.1013second # [ 1.820256] systemd[1]: Finished Create System Files and Directories.1014test # [ 1.856435] systemd[1]: Started Rule-based Manager for Device Events and Files.1015test # [ 1.861220] systemd[1]: Finished Coldplug All udev Devices.1016test # [ 1.863274] systemd[1]: Reached target System Initialization.1017test # [ 1.864619] systemd[1]: Reached target Basic System.1018second # [ 1.844883] systemd[1]: Started Rule-based Manager for Device Events and Files.1019second # [ 1.851127] systemd[1]: Finished Coldplug All udev Devices.1020second # [ 1.852882] systemd[1]: Reached target System Initialization.1021second # [ 1.853995] systemd[1]: Reached target Basic System.1022test # [ 2.265632] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121023second # [ 2.254081] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121024test # [ 2.276293] virtio_blk virtio5: 1/0/0 default/read/poll queues1025second # [ 2.262027] virtio_blk virtio5: 1/0/0 default/read/poll queues1026test # [ 2.287841] serio: i8042 KBD port at 0x60,0x64 irq 11027second # [ 2.279077] serio: i8042 KBD port at 0x60,0x64 irq 11028test # [ 2.299501] serio: i8042 AUX port at 0x60,0x64 irq 121029test # [ 2.306246] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1030second # [ 2.290011] serio: i8042 AUX port at 0x60,0x64 irq 121031second # [ 2.292043] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1032test # [ 2.319757] uhci_hcd 0000:00:1d.0: UHCI Host Controller1033second # [ 2.304689] ehci-pci 0000:00:1d.7: EHCI Host Controller1034second # [ 2.305617] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11035second # [ 2.307737] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001036test # [ 2.334869] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 11037second # [ 2.315373] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001038second # [ 2.318309] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181039second # [ 2.319687] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11040test # [ 2.342521] uhci_hcd 0000:00:1d.0: detected 2 ports1041second # [ 2.324282] usb usb1: Product: EHCI Host Controller1042second # [ 2.325100] usb usb1: Manufacturer: Linux 6.18.49 ehci_hcd1043test # [ 2.347070] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c2001044second # [ 2.327133] usb usb1: SerialNumber: 0000:00:1d.71045second # [ 2.328745] hub 1-0:1.0: USB hub found1046second # [ 2.330859] hub 1-0:1.0: 6 ports detected1047test # [ 2.352848] SCSI subsystem initialized1048second # [ 2.333862] uhci_hcd 0000:00:1d.0: UHCI Host Controller1049test # [ 2.354219] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181050second # [ 2.334756] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21051test # [ 2.355585] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11052test # [ 2.362596] usb usb1: Product: UHCI Host Controller1053test # [ 2.365445] usb usb1: Manufacturer: Linux 6.18.49 uhci_hcd1054second # [ 2.347865] uhci_hcd 0000:00:1d.0: detected 2 ports1055test # [ 2.368806] usb usb1: SerialNumber: 0000:00:1d.01056test # [ 2.371198] hub 1-0:1.0: USB hub found1057test # [ 2.373918] hub 1-0:1.0: 2 ports detected1058second # [ 2.354530] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c2001059test # [ 2.377071] ehci-pci 0000:00:1d.7: EHCI Host Controller1060test # [ 2.377940] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 21061test # [ 2.379802] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001062second # [ 2.363304] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181063second # [ 2.364652] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11064test # [ 2.387972] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001065test # [ 2.389736] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181066test # [ 2.392920] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11067test # [ 2.395732] usb usb2: Product: EHCI Host Controller1068test # [ 2.396685] usb usb2: Manufacturer: Linux 6.18.49 ehci_hcd1069test # [ 2.398929] usb usb2: SerialNumber: 0000:00:1d.71070test # [ 2.401249] hub 2-0:1.0: USB hub found1071second # [ 2.382188] usb usb2: Product: UHCI Host Controller1072test # [ 2.403485] hub 2-0:1.0: 6 ports detected1073second # [ 2.383318] usb usb2: Manufacturer: Linux 6.18.49 uhci_hcd1074test # [ 2.222486] systemd[1]: Starting Virtual Console Setup...1075second # [ 2.389161] SCSI subsystem initialized1076second # [ 2.210562] systemd[1]: Starting Virtual Console Setup...1077second # [ 2.398308] usb usb2: SerialNumber: 0000:00:1d.01078second # [ 2.218250] (udev-worker)[89]: Network interface NamePolicy= disabled on kernel command line.1079test # [ 2.429048] hub 1-0:1.0: USB hub found1080test # [ 2.430336] hub 1-0:1.0: 2 ports detected1081second # [ 2.412891] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01082test # [ 2.433926] uhci_hcd 0000:00:1d.1: UHCI Host Controller1083test # [ 2.434837] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31084test # [ 2.255110] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones.1085second # [ 2.421653] hub 2-0:1.0: USB hub found1086test # [ 2.257932] systemd[1]: Finished Virtual Console Setup.1087second # [ 2.237472] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.1088test # [ 2.451706] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01089test # [ 2.270128] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.1090second # [ 2.251881] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones.1091second # [ 2.255655] systemd[1]: Finished Virtual Console Setup.1092second # [ 2.442858] hub 2-0:1.0: 2 ports detected1093test # [ 2.277573] (udev-worker)[87]: Network interface NamePolicy= disabled on kernel command line.1094test # [ 2.468584] uhci_hcd 0000:00:1d.1: detected 2 ports1095second # [ 2.455022] uhci_hcd 0000:00:1d.1: UHCI Host Controller1096second # [ 2.455914] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31097test # [ 2.478438] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c2201098test # [ 2.488831] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181099second # [ 2.472298] uhci_hcd 0000:00:1d.1: detected 2 ports1100second # [ 2.288741] systemd[1]: Found device /dev/disk/by-label/nixos.1101test # [ 2.494975] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11102test # [ 2.497993] usb usb3: Product: UHCI Host Controller1103second # [ 2.293104] systemd[1]: Reached target Initrd Root Device.1104test # [ 2.498845] usb usb3: Manufacturer: Linux 6.18.49 uhci_hcd1105second # [ 2.295080] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1106second # [ 2.481702] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c2201107test # [ 2.319079] systemd[1]: Found device /dev/disk/by-label/nixos.1108test # [ 2.321080] systemd[1]: Reached target Initrd Root Device.1109second # [ 2.486137] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181110test # [ 2.322484] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1111test # [ 2.508801] usb usb3: SerialNumber: 0000:00:1d.11112second # [ 2.489286] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11113test # [ 2.514267] hub 3-0:1.0: USB hub found1114test # [ 2.515412] hub 3-0:1.0: 2 ports detected1115second # [ 2.495284] usb usb3: Product: UHCI Host Controller1116second # [ 2.496144] usb usb3: Manufacturer: Linux 6.18.49 uhci_hcd1117second # [ 2.502421] usb usb3: SerialNumber: 0000:00:1d.11118test # [ 2.525153] uhci_hcd 0000:00:1d.2: UHCI Host Controller1119test # [ 2.526143] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41120second # [ 2.511325] hub 3-0:1.0: USB hub found1121second # [ 2.514882] hub 3-0:1.0: 2 ports detected1122second # [ 2.331173] systemd-fsck[107]: nixos: clean, 12/65536 files, 13019/262144 blocks1123test # [ 2.539544] uhci_hcd 0000:00:1d.2: detected 2 ports1124second # [ 2.336106] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1125test # [ 2.356745] systemd-fsck[108]: nixos: clean, 12/65536 files, 13019/262144 blocks1126test # [ 2.547380] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c2401127second # [ 2.527602] uhci_hcd 0000:00:1d.2: UHCI Host Controller1128test # [ 2.364656] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1129second # [ 2.530323] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41130second # [ 2.533455] uhci_hcd 0000:00:1d.2: detected 2 ports1131test # [ 2.553753] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181132second # [ 2.534748] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1133test # [ 2.556419] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1134second # [ 2.537064] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c2401135test # [ 2.557837] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11136second # [ 2.538670] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1137test # [ 2.559614] usb usb4: Product: UHCI Host Controller1138test # [ 2.560679] usb usb4: Manufacturer: Linux 6.18.49 uhci_hcd1139second # [ 2.540117] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181140second # [ 2.541583] ahci 0000:00:1f.2: flags: 64bit ncq only1141test # [ 2.561801] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1142test # [ 2.563377] usb usb4: SerialNumber: 0000:00:1d.21143second # [ 2.543172] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11144second # [ 2.545326] usb usb4: Product: UHCI Host Controller1145test # [ 2.566023] hub 4-0:1.0: USB hub found1146second # [ 2.546201] usb usb4: Manufacturer: Linux 6.18.49 uhci_hcd1147test # [ 2.567836] ahci 0000:00:1f.2: flags: 64bit ncq only1148second # [ 2.550657] scsi host0: ahci1149test # [ 2.571654] hub 4-0:1.0: 2 ports detected1150second # [ 2.552355] scsi host1: ahci1151second # [ 2.553059] usb usb4: SerialNumber: 0000:00:1d.21152second # [ 2.555379] scsi host2: ahci1153test # [ 2.576247] scsi host0: ahci1154second # [ 2.556574] hub 4-0:1.0: USB hub found1155second # [ 2.557354] scsi host3: ahci1156test # [ 2.578753] scsi host1: ahci1157second # [ 2.560288] hub 4-0:1.0: 2 ports detected1158second # [ 2.561075] scsi host4: ahci1159test # [ 2.583080] scsi host2: ahci1160second # [ 2.563044] scsi host5: ahci1161test # [ 2.585015] scsi host3: ahci1162second # [ 2.564440] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 44 lpm-pol 11163test # [ 2.587863] scsi host4: ahci1164second # [ 2.568026] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 44 lpm-pol 11165test # [ 2.589235] scsi host5: ahci1166second # [ 2.573297] usb 1-1: new high-speed USB device number 2 using ehci-pci1167test # [ 2.596715] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 44 lpm-pol 11168second # [ 2.582339] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 44 lpm-pol 11169test # [ 2.608057] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 44 lpm-pol 11170test # [ 2.609527] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 44 lpm-pol 11171second # [ 2.590871] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 44 lpm-pol 11172second # [ 2.598299] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 44 lpm-pol 11173test # [ 2.619934] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 44 lpm-pol 11174test # [ 2.621396] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 44 lpm-pol 11175second # [ 2.603824] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 44 lpm-pol 11176test # [ 2.626990] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 44 lpm-pol 11177test # [ 2.646945] usb 2-1: new high-speed USB device number 2 using ehci-pci1178second # [ 2.704208] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001179second # [ 2.705742] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101180second # [ 2.707378] usb 1-1: Product: QEMU USB Tablet1181second # [ 2.708141] usb 1-1: Manufacturer: QEMU1182second # [ 2.709051] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11183second # [ 2.724849] hid: raw HID events driver (C) Jiri Kosina1184test # [ 2.776917] usb 2-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001185test # [ 2.778510] usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101186test # [ 2.779829] usb 2-1: Product: QEMU USB Tablet1187test # [ 2.781204] usb 2-1: Manufacturer: QEMU1188test # [ 2.782044] usb 2-1: SerialNumber: 28754-0000:00:1d.7-11189test # [ 2.600602] systemd[1]: Mounting /sysroot...1190second # [ 2.595420] systemd[1]: Mounting /sysroot...1191test # [ 2.805501] hid: raw HID events driver (C) Jiri Kosina1192test # [ 2.933457] ata1: SATA link down (SStatus 0 SControl 300)1193second # [ 2.915836] ata4: SATA link down (SStatus 0 SControl 300)1194second # [ 2.917207] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1195second # [ 2.918455] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001196second # [ 2.919678] ata3.00: applying bridge limits1197second # [ 2.920652] ata3.00: configured for UDMA/1001198test # [ 2.941503] ata4: SATA link down (SStatus 0 SControl 300)1199second # [ 2.921727] ata5: SATA link down (SStatus 0 SControl 300)1200test # [ 2.942638] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1201second # [ 2.922934] ata1: SATA link down (SStatus 0 SControl 300)1202test # [ 2.943783] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001203second # [ 2.924294] ata6: SATA link down (SStatus 0 SControl 300)1204test # [ 2.944741] ata3.00: applying bridge limits1205second # [ 2.925605] ata2: SATA link down (SStatus 0 SControl 300)1206test # [ 2.946057] ata3.00: configured for UDMA/1001207test # [ 2.947252] ata6: SATA link down (SStatus 0 SControl 300)1208second # [ 2.927081] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51209test # [ 2.948349] ata5: SATA link down (SStatus 0 SControl 300)1210test # [ 2.949685] ata2: SATA link down (SStatus 0 SControl 300)1211test # [ 2.951252] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51212second # [ 2.982320] usbcore: registered new interface driver usbhid1213test # [ 3.003312] usbcore: registered new interface driver usbhid1214second # [ 2.988571] usbhid: USB HID core driver1215test # [ 3.010453] usbhid: USB HID core driver1216second # [ 3.001679] 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/input21217test # [ 3.026655] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.0/0003:0627:0001.0001/input/input21218second # [ 3.007116] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01219second # [ 3.010917] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1220test # [ 3.030762] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01221test # [ 3.033698] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1222second # [ 3.021393] cdrom: Uniform CD-ROM driver Revision: 3.201223test # [ 3.044926] cdrom: Uniform CD-ROM driver Revision: 3.201224test # [ 3.060746] EXT4-fs (vda): mounted filesystem 9704362a-48c7-4c67-83e4-f373960181eb r/w with ordered data mode. Quota mode: none.1225second # [ 3.041087] EXT4-fs (vda): mounted filesystem 3da37e5b-65d2-44bd-9b2b-f04c95d148cf r/w with ordered data mode. Quota mode: none.1226test # [ 2.882278] systemd[1]: Mounted /sysroot.1227second # [ 2.863248] systemd[1]: Mounted /sysroot.1228test # [ 2.883359] systemd[1]: Reached target Initrd Root File System.1229second # [ 2.864684] systemd[1]: Reached target Initrd Root File System.1230test # [ 2.887321] systemd[1]: Starting Mountpoints Configured in the Real Root...1231second # [ 2.868307] systemd[1]: Starting Mountpoints Configured in the Real Root...1232test # [ 2.898897] systemd-sysroot-fstab-check[139]: /sysroot should be mounted in the initrd, will request daemon-reload.1233second # [ 2.882956] systemd-sysroot-fstab-check[141]: /sysroot should be mounted in the initrd, will request daemon-reload.1234test # [ 2.904736] systemd[1]: Reload requested from client PID 139 ('systemd-sysroot') (unit initrd-parse-etc.service)...1235test # [ 2.906655] systemd[1]: Reloading...1236second # [ 2.888085] systemd[1]: Reload requested from client PID 141 ('systemd-sysroot') (unit initrd-parse-etc.service)...1237second # [ 2.890861] systemd[1]: Reloading...1238test # [ 2.976179] systemd[1]: Reloading finished in 71 ms.1239second # [ 2.963333] systemd[1]: Reloading finished in 75 ms.1240test # [ 2.984359] systemd-sysroot-fstab-check[139]: Requesting initrd-fs.target/start/replace...1241test # [ 2.989198] systemd-sysroot-fstab-check[139]: Requesting swap.target/start/replace...1242second # [ 2.971748] systemd-sysroot-fstab-check[141]: Requesting initrd-fs.target/start/replace...1243test # [ 2.994681] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1244second # [ 2.975195] systemd-sysroot-fstab-check[141]: Requesting swap.target/start/replace...1245test # [ 2.996535] systemd[1]: Finished Mountpoints Configured in the Real Root.1246test # [ 2.997859] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1247test # [ 2.999659] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio1248second # [ 2.980458] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio1249second # [ 2.983604] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1250second # [ 2.985128] systemd[1]: Finished Mountpoints Configured in the Real Root.1251second # [ 2.986634] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1252test # [ 3.601096] systemd[1]: Mounting /sysroot/nix/.ro-store...1253test # [ 3.604142] systemd[1]: Mounting /sysroot/nix/.rw-store...1254test # [ 3.607728] systemd[1]: Mounting /sysroot/run...1255test # [ 3.612701] systemd[1]: Mounting /sysroot/tmp/shared...1256test # [ 3.616145] systemd[1]: Mounting /sysroot/tmp/xchg...1257second # [ 3.596111] systemd[1]: Mounting /sysroot/nix/.ro-store...1258second # [ 3.599088] systemd[1]: Mounting /sysroot/nix/.rw-store...1259second # [ 3.603069] systemd[1]: Mounting /sysroot/run...1260second # [ 3.608136] systemd[1]: Mounting /sysroot/tmp/shared...1261second # [ 3.612175] systemd[1]: Mounting /sysroot/tmp/xchg...1262test # [ 3.831161] 9p: Installing v9fs 9p2000 file system support1263test # [ 3.647286] systemd[1]: Mounted /sysroot/nix/.rw-store.1264test # [ 3.650642] systemd[1]: Mounted /sysroot/run.1265second # [ 3.823535] 9p: Installing v9fs 9p2000 file system support1266test # [ 3.664119] systemd[1]: Starting rw-sysroot-nix-store.service...1267second # [ 3.644746] systemd[1]: Mounted /sysroot/nix/.rw-store.1268test # [ 3.667831] systemd[1]: Mounted /sysroot/nix/.ro-store.1269test # [ 3.671125] systemd[1]: Mounted /sysroot/tmp/shared.1270test # [ 3.672123] systemd[1]: Mounted /sysroot/tmp/xchg.1271second # [ 3.653821] systemd[1]: Mounted /sysroot/nix/.ro-store.1272second # [ 3.655327] systemd[1]: Mounted /sysroot/run.1273second # [ 3.657197] systemd[1]: Mounted /sysroot/tmp/shared.1274second # [ 3.658353] systemd[1]: Mounted /sysroot/tmp/xchg.1275test # [ 3.680383] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1276test # [ 3.682493] systemd[1]: Finished rw-sysroot-nix-store.service.1277second # [ 3.662928] systemd[1]: Starting rw-sysroot-nix-store.service...1278second # [ 3.673683] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1279second # [ 3.675692] systemd[1]: Finished rw-sysroot-nix-store.service.1280test # [ 4.600402] systemd[1]: Mounting /sysroot/nix/store...1281second # [ 4.595530] systemd[1]: Mounting /sysroot/nix/store...1282test # [ 4.617927] systemd[1]: Mounted /sysroot/nix/store.1283test # [ 4.619517] systemd[1]: Reached target Initrd File Systems.1284test # [ 4.621600] systemd[1]: Starting Find NixOS closure...1285test # [ 4.625141] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1286second # [ 4.612423] systemd[1]: Mounted /sysroot/nix/store.1287second # [ 4.613965] systemd[1]: Reached target Initrd File Systems.1288second # [ 4.615920] systemd[1]: Starting Find NixOS closure...1289second # [ 4.618897] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1290test # [ 4.641295] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1291test # [ 4.644314] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1292test # [ 4.651985] systemd[1]: Finished Find NixOS closure.1293test # [ 4.653477] systemd[1]: Reached target Initrd Default Target.1294test # [ 4.655406] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1295second # [ 4.635102] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1296second # [ 4.638942] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1297second # [ 4.644426] systemd[1]: Finished Find NixOS closure.1298second # [ 4.646252] systemd[1]: Reached target Initrd Default Target.1299test # [ 4.667759] systemd[1]: Stopped target Initrd Default Target.1300second # [ 4.647790] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1301test # [ 4.669093] systemd[1]: Stopped target Basic System.1302test # [ 4.671160] systemd[1]: Stopped target Initrd Root Device.1303test # [ 4.672166] systemd[1]: Stopped target Path Units.1304test # [ 4.673080] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1305test # [ 4.674400] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1306test # [ 4.675732] systemd[1]: Stopped target Slice Units.1307test # [ 4.676946] systemd[1]: Stopped target Socket Units.1308test # [ 4.679174] systemd[1]: Stopped target System Initialization.1309test # [ 4.680307] systemd[1]: Stopped target Swaps.1310test # [ 4.681156] systemd[1]: Stopped target Timer Units.1311second # [ 4.660620] systemd[1]: Stopped target Initrd Default Target.1312test # [ 4.682096] systemd[1]: dbus.socket: Deactivated successfully.1313second # [ 4.661972] systemd[1]: Stopped target Basic System.1314test # [ 4.683197] systemd[1]: Closed D-Bus System Message Bus Socket.1315second # [ 4.663266] systemd[1]: Stopped target Initrd Root Device.1316test # [ 4.684897] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1317second # [ 4.665135] systemd[1]: Stopped target Path Units.1318test # [ 4.686292] systemd[1]: Stopped Find NixOS closure.1319second # [ 4.666082] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1320second # [ 4.667506] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1321test # [ 4.687552] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio1322second # [ 4.668844] systemd[1]: Stopped target Slice Units.1323test # [ 4.690190] systemd[1]: Starting rw-sysroot-nix-store.service...1324second # [ 4.670204] systemd[1]: Stopped target Socket Units.1325test # [ 4.691345] systemd[1]: systemd-sysctl.service: Deactivated successfully.1326second # [ 4.671478] systemd[1]: Stopped target System Initialization.1327test # [ 4.692776] systemd[1]: Stopped Apply Kernel Variables.1328second # [ 4.672932] systemd[1]: Stopped target Swaps.1329second # [ 4.674226] systemd[1]: Stopped target Timer Units.1330test # [ 4.695175] systemd[1]: systemd-modules-load.service: Deactivated successfully.1331second # [ 4.675703] systemd[1]: dbus.socket: Deactivated successfully.1332test # [ 4.696623] systemd[1]: Stopped Load Kernel Modules.1333second # [ 4.676819] systemd[1]: Closed D-Bus System Message Bus Socket.1334test # [ 4.698199] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1335second # [ 4.678122] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1336second # [ 4.679754] systemd[1]: Stopped Find NixOS closure.1337test # [ 4.699752] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1338test # [ 4.701553] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1339second # [ 4.681156] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio1340second # [ 4.683093] systemd[1]: Starting rw-sysroot-nix-store.service...1341test # [ 4.704157] systemd[1]: Stopped Create System Files and Directories.1342test # [ 4.705391] systemd[1]: Stopped target Local File Systems.1343second # [ 4.685151] systemd[1]: systemd-sysctl.service: Deactivated successfully.1344second # [ 4.686389] systemd[1]: Stopped Apply Kernel Variables.1345test # [ 4.706679] systemd[1]: Stopped target Preparation for Local File Systems.1346second # [ 4.687503] systemd[1]: systemd-modules-load.service: Deactivated successfully.1347test # [ 4.708646] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1348second # [ 4.688769] systemd[1]: Stopped Load Kernel Modules.1349test # [ 4.710522] systemd[1]: Stopped Coldplug All udev Devices.1350second # [ 4.691153] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1351second # [ 4.692525] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1352test # [ 4.713173] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1353second # [ 4.693883] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1354test # [ 4.714627] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1355second # [ 4.695202] systemd[1]: Stopped Create System Files and Directories.1356second # [ 4.696355] systemd[1]: Stopped target Local File Systems.1357test # [ 4.717105] systemd[1]: Stopped Virtual Console Setup.1358second # [ 4.697657] systemd[1]: Stopped target Preparation for Local File Systems.1359second # [ 4.699158] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1360second # [ 4.700604] systemd[1]: Stopped Coldplug All udev Devices.1361second # [ 4.703154] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1362second # [ 4.704519] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1363test # [ 4.725752] systemd[1]: initrd-cleanup.service: Deactivated successfully.1364second # [ 4.706787] systemd[1]: Stopped Virtual Console Setup.1365test # [ 4.729080] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1366test # [ 4.732184] systemd[1]: systemd-udevd.service: Deactivated successfully.1367second # [ 4.712610] systemd[1]: initrd-cleanup.service: Deactivated successfully.1368test # [ 4.734245] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1369second # [ 4.717195] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1370test # [ 4.738806] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1371test # [ 4.740714] systemd[1]: Finished rw-sysroot-nix-store.service.1372test # [ 4.742954] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1373test # [ 4.744344] systemd[1]: Closed udev Control Socket.1374second # [ 4.723664] systemd[1]: systemd-udevd.service: Deactivated successfully.1375test # [ 4.745493] systemd[1]: Starting Cleanup udev Database...1376second # [ 4.725165] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1377test # [ 4.747081] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1378second # [ 4.727365] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1379test # [ 4.748437] systemd[1]: Stopped Create Static Device Nodes in /dev.1380second # [ 4.728746] systemd[1]: Finished rw-sysroot-nix-store.service.1381test # [ 4.749589] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1382test # [ 4.751166] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1383second # [ 4.731183] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1384second # [ 4.732559] systemd[1]: Closed udev Control Socket.1385test # [ 4.753172] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1386test # [ 4.754388] systemd[1]: Stopped Create List of Static Device Nodes.1387second # [ 4.734313] systemd[1]: Starting Cleanup udev Database...1388second # [ 4.735382] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1389second # [ 4.737232] systemd[1]: Stopped Create Static Device Nodes in /dev.1390second # [ 4.738492] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1391second # [ 4.740164] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1392second # [ 4.741708] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1393second # [ 4.743159] systemd[1]: Stopped Create List of Static Device Nodes.1394test # [ 4.769381] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1395test # [ 4.771107] systemd[1]: Finished Cleanup udev Database.1396test # [ 4.772603] systemd[1]: Reached target Switch Root.1397test # [ 4.774419] systemd[1]: Starting NixOS Activation...1398second # [ 4.754914] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1399second # [ 4.756927] systemd[1]: Finished Cleanup udev Database.1400second # [ 4.759195] systemd[1]: Reached target Switch Root.1401second # [ 4.760848] systemd[1]: Starting NixOS Activation...1402test # [ 4.859352] initrd-nixos-activation-start[193]: booting system configuration /nix/store/swxa86y3wmqx1dr86miw20bagz8rxc7l-nixos-system-test-test1403second # [ 4.844458] initrd-nixos-activation-start[193]: booting system configuration /nix/store/41grk726jb1nqr68nw7vkjsf12lbnw7r-nixos-system-second-test1404test # [ 4.892750] initrd-nixos-activation-start[193]: running activation script...1405second # [ 4.877494] initrd-nixos-activation-start[193]: running activation script...1406second # [ 5.094894] initrd-nixos-activation-start[216]: setting up /etc...1407test # [ 5.116388] initrd-nixos-activation-start[216]: setting up /etc...1408second # [ 5.217892] initrd-nixos-activation-start[216]: setting up age secrets...1409test # [ 5.244241] initrd-nixos-activation-start[216]: setting up age secrets...1410second # [ 5.332291] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1411second # [ 5.335142] systemd[1]: Finished NixOS Activation.1412second # [ 5.336393] systemd[1]: Starting Switch Root...1413second # [ 5.347962] systemd[1]: Switching root.1414test # [ 5.407749] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1415test # [ 5.410096] systemd[1]: Finished NixOS Activation.1416test # [ 5.411777] systemd[1]: Starting Switch Root...1417test # [ 5.423616] systemd[1]: Switching root.1418second # [ 5.665130] systemd-journald[67]: Received SIGTERM from PID 1 (systemd).1419test # [ 5.731165] systemd-journald[67]: Received SIGTERM from PID 1 (systemd).1420second # [ 5.741169] NET: Registered PF_VSOCK protocol family1421test # [ 5.810412] NET: Registered PF_VSOCK protocol family1422second # [ 6.102353] 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)1423second # [ 6.106798] systemd[1]: Detected virtualization kvm.1424second # [ 6.107649] systemd[1]: Detected architecture x86-64.1425second # [ 6.108555] systemd[1]: Detected first boot.1426second # [ 6.110694] systemd[1]: Initializing machine ID from random generator.1427test # [ 6.171356] 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)1428test # [ 6.175943] systemd[1]: Detected virtualization kvm.1429test # [ 6.176774] systemd[1]: Detected architecture x86-64.1430test # [ 6.177709] systemd[1]: Detected first boot.1431test # [ 6.180187] systemd[1]: Initializing machine ID from random generator.1432second # [ 6.302464] systemd[1]: bpf-restrict-fs: LSM BPF program attached1433test # [ 6.375272] systemd[1]: bpf-restrict-fs: LSM BPF program attached1434second # [ 6.391419] systemd[1]: Applying preset policy.1435test # [ 6.455653] systemd[1]: Applying preset policy.1436second # [ 6.713952] systemd[1]: Populated /etc with preset unit settings.1437test # [ 6.772113] systemd[1]: Populated /etc with preset unit settings.1438second # [ 6.996471] systemd[1]: initrd-switch-root.service: Deactivated successfully.1439second # [ 6.998200] systemd[1]: Stopped initrd-switch-root.service.1440second # [ 7.000862] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1441second # [ 7.003328] systemd[1]: Created slice Slice /system/getty.1442second # [ 7.004851] systemd[1]: Created slice User and Session Slice.1443second # [ 7.006025] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1444second # [ 7.007565] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1445second # [ 7.017496] systemd[1]: Expecting device /dev/hvc0...1446second # [ 7.018434] systemd[1]: Expecting device /dev/ttyS0...1447second # [ 7.019399] systemd[1]: Reached target Local Encrypted Volumes.1448second # [ 7.020453] systemd[1]: Stopped target initrd-fs.target.1449second # [ 7.021414] systemd[1]: Stopped target initrd-root-fs.target.1450second # [ 7.022437] systemd[1]: Stopped target initrd-switch-root.target.1451second # [ 7.023534] systemd[1]: Reached target Virtual Machines and Containers.1452second # [ 7.024702] systemd[1]: Reached target Path Units.1453second # [ 7.025606] systemd[1]: Reached target Remote File Systems.1454second # [ 7.026618] systemd[1]: Reached target Slice Units.1455second # [ 7.027553] systemd[1]: Reached target Swaps.1456second # [ 7.029862] systemd[1]: Listening on Query the User Interactively for a Password.1457second # [ 7.032845] systemd[1]: Listening on Process Core Dump Socket.1458second # [ 7.035005] systemd[1]: Listening on Credential Encryption/Decryption.1459second # [ 7.037269] systemd[1]: Listening on Factory Reset Management.1460second # [ 7.038386] systemd[1]: Listening on Hostname Service Socket.1461second # [ 7.041590] systemd[1]: Starting Journal Log Access Socket...1462second # [ 7.042953] systemd[1]: Listening on Journal Audit Socket.1463second # [ 7.045568] systemd[1]: Listening on Console Output Muting Service Socket.1464second # [ 7.046984] systemd[1]: Listening on Network Management Metrics Varlink Socket.1465second # [ 7.048552] systemd[1]: Listening on Network Management Varlink Socket.1466second # [ 7.049820] systemd[1]: Listening on Network Management Netlink Socket.1467test # [ 7.069989] systemd[1]: initrd-switch-root.service: Deactivated successfully.1468second # [ 7.051085] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1469test # [ 7.071732] systemd[1]: Stopped initrd-switch-root.service.1470second # [ 7.052544] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1471second # [ 7.054105] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1472test # [ 7.074544] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1473test # [ 7.076911] systemd[1]: Created slice Slice /system/getty.1474test # [ 7.078394] systemd[1]: Created slice User and Session Slice.1475second # [ 7.059203] systemd[1]: Listening on Disk Repartitioning Service Socket.1476test # [ 7.079598] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1477second # [ 7.060458] systemd[1]: Listening on Resolve Monitor Varlink Socket.1478test # [ 7.081131] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1479second # [ 7.061662] systemd[1]: Listening on Resolve Service Varlink Socket.1480test # [ 7.082399] systemd[1]: Expecting device /dev/hvc0...1481second # [ 7.062836] systemd[1]: Listening on udev Control Socket.1482second # [ 7.063873] systemd[1]: Listening on udev Varlink Socket.1483second # [ 7.066696] systemd[1]: Mounting Huge Pages File System...1484second # [ 7.070774] systemd[1]: Mounting POSIX Message Queue File System...1485test # [ 7.083283] systemd[1]: Expecting device /dev/ttyS0...1486test # [ 7.092677] systemd[1]: Reached target Local Encrypted Volumes.1487test # [ 7.093711] systemd[1]: Stopped target initrd-fs.target.1488test # [ 7.094668] systemd[1]: Stopped target initrd-root-fs.target.1489second # [ 7.075550] systemd[1]: Mounting Kernel Debug File System...1490test # [ 7.095664] systemd[1]: Stopped target initrd-switch-root.target.1491test # [ 7.096740] systemd[1]: Reached target Virtual Machines and Containers.1492test # [ 7.097890] systemd[1]: Reached target Path Units.1493test # [ 7.098737] systemd[1]: Reached target Remote File Systems.1494test # [ 7.099720] systemd[1]: Reached target Slice Units.1495test # [ 7.100626] systemd[1]: Reached target Swaps.1496second # [ 7.080864] systemd[1]: Mounting Kernel Trace File System...1497test # [ 7.102933] systemd[1]: Listening on Query the User Interactively for a Password.1498test # [ 7.105768] systemd[1]: Listening on Process Core Dump Socket.1499test # [ 7.108000] systemd[1]: Listening on Credential Encryption/Decryption.1500second # [ 7.089328] systemd[1]: Starting Create List of Static Device Nodes...1501test # [ 7.110244] systemd[1]: Listening on Factory Reset Management.1502test # [ 7.111355] systemd[1]: Listening on Hostname Service Socket.1503second # [ 7.091359] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio1504test # [ 7.114382] systemd[1]: Starting Journal Log Access Socket...1505test # [ 7.116084] systemd[1]: Listening on Journal Audit Socket.1506test # [ 7.118668] systemd[1]: Listening on Console Output Muting Service Socket.1507test # [ 7.120015] systemd[1]: Listening on Network Management Metrics Varlink Socket.1508test # [ 7.121456] systemd[1]: Listening on Network Management Varlink Socket.1509test # [ 7.122690] systemd[1]: Listening on Network Management Netlink Socket.1510test # [ 7.123977] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1511test # [ 7.125403] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1512test # [ 7.126963] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1513second # [ 7.108565] systemd[1]: Starting Load Kernel Module configfs...1514test # [ 7.132528] systemd[1]: Listening on Disk Repartitioning Service Socket.1515test # [ 7.133769] systemd[1]: Listening on Resolve Monitor Varlink Socket.1516second # [ 7.113285] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1517test # [ 7.134976] systemd[1]: Listening on Resolve Service Varlink Socket.1518test # [ 7.136159] systemd[1]: Listening on udev Control Socket.1519test # [ 7.137226] systemd[1]: Listening on udev Varlink Socket.1520test # [ 7.140013] systemd[1]: Mounting Huge Pages File System...1521second # [ 7.120148] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1522test # [ 7.142914] systemd[1]: Mounting POSIX Message Queue File System...1523second # [ 7.123874] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1524test # [ 7.148765] systemd[1]: Mounting Kernel Debug File System...1525second # [ 7.131106] systemd[1]: Mounting FUSE Control File System...1526test # [ 7.154103] systemd[1]: Mounting Kernel Trace File System...1527second # [ 7.132368] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671528test # [ 7.161111] systemd[1]: Starting Create List of Static Device Nodes...1529test # [ 7.162783] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio1530second # [ 7.145248] systemd[1]: Starting Journal Service...1531second # [ 7.151001] systemd[1]: Starting Load Kernel Modules...1532test # [ 7.172634] systemd[1]: Starting Load Kernel Module configfs...1533second # [ 7.161513] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1534test # [ 7.182825] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1535test # [ 7.189364] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1536second # [ 7.171791] systemd[1]: Starting Remount Root and Kernel File Systems...1537test # [ 7.191665] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1538second # [ 7.182065] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1539test # [ 7.206081] systemd[1]: Mounting FUSE Control File System...1540test # [ 7.207659] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671541second # [ 7.192968] systemd[1]: Starting Coldplug All udev Devices...1542test # [ 7.217422] systemd[1]: Starting Journal Service...1543test # [ 7.224439] systemd[1]: Starting Load Kernel Modules...1544second # [ 7.205981] systemd-journald[319]: Collecting audit messages is enabled.1545second # [ 7.217372] loop: module loaded1546test # [ 7.238273] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1547second # [ 7.219760] systemd[1]: Listening on Journal Log Access Socket.1548second # [ 7.224915] EXT4-fs (vda): re-mounted 3da37e5b-65d2-44bd-9b2b-f04c95d148cf.1549second # [ 7.228086] systemd[1]: Mounted Huge Pages File System.1550second # [ 7.233553] systemd[1]: Mounted POSIX Message Queue File System.1551test # [ 7.253964] systemd[1]: Starting Remount Root and Kernel File Systems...1552test # [ 7.256006] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1553second # [ 7.237503] systemd[1]: Mounted Kernel Debug File System.1554second # [ 7.240966] systemd[1]: Mounted Kernel Trace File System.1555test # [ 7.263568] systemd-journald[341]: Collecting audit messages is enabled.1556second # [ 7.247074] systemd[1]: Finished Create List of Static Device Nodes.1557second # [ 7.065246] systemd[1]: Queued start job for default target Multi-User System.1558second # [ 7.253801] systemd[1]: Started Journal Service.1559test # [ 7.274408] systemd[1]: Starting Coldplug All udev Devices...1560second # [ 7.068102] systemd[1]: systemd-journald.service: Deactivated successfully.1561second # [ 7.071379] systemd-modules-load[320]: Module 'ctr' is built in1562second # [ 7.075388] systemd-modules-load[320]: Inserted module 'loop'1563second # [ 7.078399] systemd[1]: modprobe@configfs.service: Deactivated successfully.1564second # [ 7.081373] systemd[1]: Finished Load Kernel Module configfs.1565test # [ 7.288027] loop: module loaded1566second # [ 7.082720] systemd[1]: Mounted FUSE Control File System.1567second # [ 7.088217] systemd[1]: Finished Load Kernel Modules.1568test # [ 7.296201] systemd[1]: Listening on Journal Log Access Socket.1569second # [ 7.092739] systemd[1]: Finished Remount Root and Kernel File Systems.1570test # [ 7.116993] systemd[1]: Queued start job for default target Multi-User System.1571test # [ 7.118562] systemd[1]: systemd-journald.service: Deactivated successfully.1572test # [ 7.305230] systemd[1]: Started Journal Service.1573test # [ 7.122330] systemd-modules-load[342]: Module 'ctr' is built in1574second # [ 7.104236] systemd-oomd[322]: No swap; memory pressure usage will be degraded1575test # [ 7.125342] systemd-modules-load[342]: Inserted module 'loop'1576second # [ 7.109126] systemd[1]: Listening on Disk Image Download Service Socket.1577test # [ 7.132441] systemd[1]: Mounted Huge Pages File System.1578test # [ 7.134405] systemd[1]: Mounted POSIX Message Queue File System.1579second # [ 7.115182] systemd[1]: Mounting Kernel Configuration File System...1580test # [ 7.321158] EXT4-fs (vda): re-mounted 9704362a-48c7-4c67-83e4-f373960181eb.1581test # [ 7.138428] systemd[1]: Mounted Kernel Debug File System.1582second # [ 7.119702] systemd[1]: Starting Firewall...1583test # [ 7.141170] systemd[1]: Mounted Kernel Trace File System.1584test # [ 7.143716] systemd[1]: Finished Create List of Static Device Nodes.1585test # [ 7.147223] systemd[1]: modprobe@configfs.service: Deactivated successfully.1586test # [ 7.148705] systemd[1]: Finished Load Kernel Module configfs.1587second # [ 7.128362] systemd[1]: Starting Flush Journal to Persistent Storage...1588test # [ 7.150485] systemd[1]: Mounted FUSE Control File System.1589second # [ 7.129603] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1590test # [ 7.154610] systemd[1]: Finished Load Kernel Modules.1591second # [ 7.136137] systemd[1]: Starting Load/Save OS Random Seed...1592test # [ 7.160144] systemd[1]: Finished Remount Root and Kernel File Systems.1593second # [ 7.156897] systemd[1]: Starting Apply Kernel Variables...1594test # [ 7.180134] systemd[1]: Listening on Disk Image Download Service Socket.1595test # [ 7.185711] systemd[1]: Mounting Kernel Configuration File System...1596second # [ 7.168064] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1597second # [ 7.169530] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1598second # [ 7.171559] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1599test # [ 7.192202] systemd-oomd[344]: No swap; memory pressure usage will be degraded1600test # [ 7.195567] systemd[1]: Starting Firewall...1601test # [ 7.206089] systemd[1]: Starting Flush Journal to Persistent Storage...1602test # [ 7.207500] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1603test # [ 7.217196] systemd[1]: Starting Load/Save OS Random Seed...1604second # [ 7.383964] systemd-journald[319]: Received client request to flush runtime journal.1605test # [ 7.231204] systemd[1]: Starting Apply Kernel Variables...1606test # [ 7.245950] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1607test # [ 7.249660] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1608test # [ 7.251734] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1609test # [ 7.447473] systemd-journald[341]: Received client request to flush runtime journal.1610second # [ 7.440990] systemd[1]: Finished Load/Save OS Random Seed.1611second # [ 7.445623] systemd[1]: Mounted Kernel Configuration File System.1612second # [ 7.449587] systemd[1]: Reached target First Boot Complete.1613second # [ 7.453966] systemd[1]: Finished Apply Kernel Variables.1614second # [ 7.457694] systemd[1]: Starting Network Name Resolution...1615second # [ 7.458870] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1616second # [ 7.463447] systemd[1]: Starting Create Static Device Nodes in /dev...1617second # [ 7.466161] systemd[1]: Finished Create Static Device Nodes in /dev.1618second # [ 7.467875] systemd[1]: Reached target Preparation for Local File Systems.1619second # [ 7.470604] systemd[1]: Starting Rule-based Manager for Device Events and Files...1620second # [ 7.473505] systemd-resolved[348]: Positive Trust Anchors:1621second # [ 7.476212] systemd-resolved[348]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1622second # [ 7.479197] systemd-resolved[348]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161623second # [ 7.482155] systemd-resolved[348]: 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 test1624test # [ 7.511226] systemd[1]: Mounted Kernel Configuration File System.1625test # [ 7.513962] systemd[1]: Finished Load/Save OS Random Seed.1626test # [ 7.518530] systemd[1]: Reached target First Boot Complete.1627second # [ 7.498795] systemd[1]: Finished Flush Journal to Persistent Storage.1628test # [ 7.522387] systemd[1]: Finished Apply Kernel Variables.1629test # [ 7.525484] systemd[1]: Starting Network Name Resolution...1630test # [ 7.528322] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1631second # [ 7.511885] systemd-udevd[359]: Using default interface naming scheme 'v261'.1632test # [ 7.536375] systemd[1]: Starting Create Static Device Nodes in /dev...1633test # [ 7.537698] systemd[1]: Finished Create Static Device Nodes in /dev.1634test # [ 7.538822] systemd[1]: Reached target Preparation for Local File Systems.1635test # [ 7.541838] systemd[1]: Starting Rule-based Manager for Device Events and Files...1636test # [ 7.546195] systemd-resolved[369]: Positive Trust Anchors:1637test # [ 7.548246] systemd-resolved[369]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1638test # [ 7.550921] systemd-resolved[369]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161639second # [ 7.534962] systemd[1]: Finished Coldplug All udev Devices.1640test # [ 7.553855] systemd-resolved[369]: 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 test1641test # [ 7.573901] systemd[1]: Finished Flush Journal to Persistent Storage.1642second # [ 7.557236] systemd-resolved[348]: Using system hostname 'second'.1643second # [ 7.564753] systemd[1]: Started Network Name Resolution.1644test # [ 7.590108] systemd-udevd[380]: Using default interface naming scheme 'v261'.1645test # [ 7.625194] systemd[1]: Finished Coldplug All udev Devices.1646test # [ 7.631854] systemd-resolved[369]: Using system hostname 'test'.1647test # [ 7.638697] systemd[1]: Started Network Name Resolution.1648second # [ 7.618097] systemd[1]: Started Rule-based Manager for Device Events and Files.1649test # [ 7.698088] systemd[1]: Started Rule-based Manager for Device Events and Files.1650second # [ 7.777840] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1651second # [ 7.816662] systemd[1]: Mounting /run/wrappers...1652test # [ 7.864195] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1653second # [ 7.845081] systemd[1]: Mounted /run/wrappers.1654second # [ 7.845975] systemd[1]: Reached target Local File Systems.1655second # [ 7.850238] systemd[1]: Listening on Boot Loader Control Service Socket.1656second # [ 7.854110] systemd[1]: Starting register-nix-paths.service...1657second # [ 7.856698] systemd[1]: Starting Create SUID/SGID Wrappers...1658second # [ 7.857757] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1659test # [ 7.889878] systemd[1]: Mounting /run/wrappers...1660second # [ 7.872953] systemd[1]: Starting Save Transient machine-id to Disk...1661second # [ 7.880448] systemd[1]: Starting Create System Files and Directories...1662test # [ 7.918924] systemd[1]: Mounted /run/wrappers.1663test # [ 7.920464] systemd[1]: Reached target Local File Systems.1664test # [ 7.924657] systemd[1]: Listening on Boot Loader Control Service Socket.1665test # [ 7.930474] systemd[1]: Starting register-nix-paths.service...1666test # [ 7.933645] systemd[1]: Starting Create SUID/SGID Wrappers...1667test # [ 7.935459] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1668test # [ 7.947440] systemd[1]: Starting Save Transient machine-id to Disk...1669second # [ 7.934705] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1670test # [ 7.957128] systemd[1]: Starting Create System Files and Directories...1671second # [ 7.952370] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1672second # [ 7.961348] systemd[1]: Finished Save Transient machine-id to Disk.1673test # [ 8.018698] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1674test # [ 8.027189] systemd[1]: Finished Save Transient machine-id to Disk.1675test # [ 8.035482] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1676second # [ 8.017768] systemd[1]: Finished Create System Files and Directories.1677second # [ 8.044426] systemd[1]: Starting Rebuild Journal Catalog...1678second # [ 8.055405] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1679test # [ 8.077945] systemd[1]: Finished Create System Files and Directories.1680second # [ 8.058737] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1681test # [ 8.091156] systemd[1]: Starting Rebuild Journal Catalog...1682test # [ 8.096094] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1683test # [ 8.113984] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1684second # [ 8.126748] (udev-worker)[413]: Network interface NamePolicy= disabled on kernel command line.1685second # [ 8.136653] (udev-worker)[415]: Network interface NamePolicy= disabled on kernel command line.1686test # [ 8.168290] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1687second # [ 8.160804] systemd[1]: Finished Firewall.1688second # [ 8.167633] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1689second # [ 8.168971] systemd[1]: Reached target Preparation for Network.1690second # [ 8.176526] systemd[1]: Listening on Network Management Resolve Hook Socket.1691test # [ 8.201861] systemd[1]: Finished Rebuild Journal Catalog.1692second # [ 8.188462] systemd[1]: Starting Network Management...1693test # [ 8.210763] systemd[1]: Starting Update is Completed...1694test # [ 8.226381] systemd[1]: Finished Firewall.1695test # [ 8.228458] systemd[1]: Reached target Preparation for Network.1696test # [ 8.229961] systemd[1]: Listening on Network Management Resolve Hook Socket.1697test # [ 8.238626] (udev-worker)[438]: Network interface NamePolicy= disabled on kernel command line.1698second # [ 8.219175] systemd[1]: Finished Rebuild Journal Catalog.1699test # [ 8.242962] systemd[1]: Starting Network Management...1700test # [ 8.245343] (udev-worker)[432]: Network interface NamePolicy= disabled on kernel command line.1701second # [ 8.227287] systemd[1]: Starting Update is Completed...1702test # [ 8.277759] systemd[1]: Finished Update is Completed.1703second # [ 8.280563] systemd[1]: Finished Update is Completed.1704second # [ 8.418174] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1705second # [ 8.423278] systemd[1]: Finished Create SUID/SGID Wrappers.1706test # [ 8.449281] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1707test # [ 8.454543] systemd[1]: Finished Create SUID/SGID Wrappers.1708second # [ 8.660565] cfg80211: Loading compiled-in X.509 certificates for regulatory database1709test # [ 8.697194] cfg80211: Loading compiled-in X.509 certificates for regulatory database1710second # [ 8.684797] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1711second # [ 8.685847] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1712test # [ 8.716724] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1713test # [ 8.717758] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1714second # [ 8.541788] systemd-networkd[509]: /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.1715second # [ 8.547415] systemd-networkd[509]: /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.1716second # [ 8.552774] systemd-networkd[509]: lo: Link UP1717second # [ 8.554296] systemd-networkd[509]: lo: Gained carrier1718test # [ 8.575684] systemd-networkd[532]: /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.1719test # [ 8.580834] systemd-networkd[532]: /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.1720test # [ 8.586278] systemd-networkd[532]: lo: Link UP1721second # [ 8.565979] systemd[1]: Started Network Management.1722test # [ 8.587262] systemd-networkd[532]: lo: Gained carrier1723second # [ 8.572313] systemd-networkd[509]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1724second # [ 8.576341] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1725second # [ 8.578353] systemd-networkd[509]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1726test # [ 8.600636] systemd[1]: Started Network Management.1727test # [ 8.604421] systemd-networkd[532]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1728second # [ 8.586082] systemd-networkd[509]: eth1: Link UP1729second # [ 8.586993] systemd-networkd[509]: eth1: Gained carrier1730test # [ 8.609393] systemd-networkd[532]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1731second # [ 8.588062] systemd-networkd[509]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1732test # [ 8.611411] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1733test # [ 8.619312] systemd-networkd[532]: eth1: Link UP1734test # [ 8.620270] systemd-networkd[532]: eth1: Gained carrier1735test # [ 8.621267] systemd-networkd[532]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1736second # [ 8.795189] NET: Registered PF_PACKET protocol family1737second # [ 8.816057] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31738test # [ 8.838078] NET: Registered PF_PACKET protocol family1739second # [ 8.633855] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1740second # [ 8.639543] systemd[1]: Finished register-nix-paths.service.1741second # [ 8.641311] systemd[1]: Reached target System Initialization.1742second # [ 8.644723] systemd[1]: Started Discard unused filesystem blocks once a week.1743second # [ 8.647342] systemd[1]: Started Daily Cleanup of Temporary Directories.1744second # [ 8.649251] systemd[1]: Reached target Timer Units.1745second # [ 8.836163] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1746second # [ 8.652206] systemd[1]: Listening on D-Bus System Message Bus Socket.1747second # [ 8.653558] systemd[1]: Listening on Nix Daemon Socket.1748test # [ 8.675190] systemd[1]: Finished register-nix-paths.service.1749test # [ 8.678117] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1750second # [ 8.658326] systemd[1]: Listening on Authorization Manager Agent Helper.1751second # [ 8.659557] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1752test # [ 8.681254] systemd[1]: Reached target System Initialization.1753second # [ 8.661131] systemd[1]: Reached target Socket Units.1754test # [ 8.684470] systemd[1]: Started Discard unused filesystem blocks once a week.1755test # [ 8.685745] systemd[1]: Started Daily Cleanup of Temporary Directories.1756second # [ 8.665625] systemd[1]: Starting D-Bus System Message Bus...1757test # [ 8.688354] systemd[1]: Reached target Timer Units.1758test # [ 8.875068] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31759test # [ 8.691618] systemd[1]: Listening on D-Bus System Message Bus Socket.1760test # [ 8.692860] systemd[1]: Listening on Nix Daemon Socket.1761test # [ 8.697084] systemd[1]: Listening on Authorization Manager Agent Helper.1762test # [ 8.698506] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1763test # [ 8.700179] systemd[1]: Reached target Socket Units.1764test # [ 8.705082] systemd[1]: Starting D-Bus System Message Bus...1765test # [ 8.922914] ACPI: button: Power Button [PWRF]1766test # [ 8.927124] mousedev: PS/2 mouse device common for all mice1767second # [ 8.742897] dbus-broker-launch[555]: Looking up NSS user entry for 'systemd-timesync'...1768second # [ 8.747116] dbus-broker-launch[555]: NSS returned no entry for 'systemd-timesync'1769second # [ 8.748986] dbus-broker-launch[555]: Invalid user-name in /nix/store/52kvjg19h04x096xdxhxzszgvqskmsc2-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1770test # [ 8.774848] dbus-broker-launch[577]: Looking up NSS user entry for 'systemd-timesync'...1771test # [ 8.779459] dbus-broker-launch[577]: NSS returned no entry for 'systemd-timesync'1772test # [ 8.781192] dbus-broker-launch[577]: Invalid user-name in /nix/store/52kvjg19h04x096xdxhxzszgvqskmsc2-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1773second # [ 8.762200] systemd-resolved[348]: Clock change detected. Flushing caches.1774test # [ 8.981886] rtc_cmos 00:04: RTC can wake from S41775second # [ 8.778769] dbus-broker-launch[555]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/6qz6q8j2r2cirh33f67mpdsxnqf8d680-networkmanager-1.58.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1776test # [ 8.802706] dbus-broker-launch[577]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/6qz6q8j2r2cirh33f67mpdsxnqf8d680-networkmanager-1.58.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1777test # [ 8.806465] dbus-broker-launch[577]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/6qz6q8j2r2cirh33f67mpdsxnqf8d680-networkmanager-1.58.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1778second # [ 8.789917] dbus-broker-launch[555]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/6qz6q8j2r2cirh33f67mpdsxnqf8d680-networkmanager-1.58.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1779test # [ 8.810532] dbus-broker-launch[577]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/nhsrpbakhmswx0ykmkxj8q0ykm2ifjlh-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1780test # [ 8.816098] dbus-broker-launch[577]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/3pj4zjhsfxh3fbj02aqm1pxkyf2d1r7f-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1781second # [ 8.796654] dbus-broker-launch[555]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/nhsrpbakhmswx0ykmkxj8q0ykm2ifjlh-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1782test # [ 8.819841] systemd[1]: Started D-Bus System Message Bus.1783test # [ 8.822806] systemd[1]: Reached target Basic System.1784test # [ 8.825965] systemd[1]: Starting Network Manager Script Dispatcher Service...1785test # [ 8.830213] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1786second # [ 8.808377] dbus-broker-launch[555]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/3pj4zjhsfxh3fbj02aqm1pxkyf2d1r7f-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1787second # [ 8.814352] systemd[1]: Started D-Bus System Message Bus.1788test # [ 9.021189] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1789second # [ 8.820090] systemd[1]: Reached target Basic System.1790test # [ 8.842336] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1791second # [ 8.822318] systemd[1]: Starting Network Manager Script Dispatcher Service...1792second # [ 8.823815] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1793second # [ 8.840129] mousedev: PS/2 mouse device common for all mice1794second # [ 8.873165] ACPI: button: Power Button [PWRF]1795second # [ 8.926011] rtc_cmos 00:04: RTC can wake from S41796second # [ 8.946333] rtc_cmos 00:04: registered as rtc01797second # [ 8.946431] rtc_cmos 00:04: setting system clock to 2026-09-04T20:05:01 UTC (1788552301)1798second # [ 8.832850] systemd[1]: Starting Network Manager...1799test # [ 8.854701] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1800second # [ 8.836267] dbus-broker-launch[555]: Ready1801test # [ 8.859074] dbus-broker-launch[577]: Ready1802second # [ 8.844056] systemd[1]: Started backdoor.service.1803test # [ 8.876105] systemd[1]: Starting Network Manager...1804test # [ 8.877868] systemd-resolved[369]: Clock change detected. Flushing caches.1805second # [ 8.857772] systemd[1]: Starting Import lastlog data into lastlog2 database...1806second # [ 8.875072] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1807second # [ 8.881346] fwqmh4fc3j6in9rflv5cf5kgmc9k4ybb-wifi-secrets[559]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1808second # [ 8.905092] systemd[1]: Starting Post-Boot Actions...1809test # [ 8.933979] systemd[1]: Started backdoor.service.1810test # [ 8.940608] fwqmh4fc3j6in9rflv5cf5kgmc9k4ybb-wifi-secrets[580]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1811second # [ 8.946519] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1812second # [ 8.948745] systemd-journald[319]: Time jumped backwards, rotating.1813test # [ 8.951627] systemd[1]: Starting Import lastlog data into lastlog2 database...1814second # [ 8.934253] systemd[1]: Started Reset console on configuration changes.1815test # [ 8.980347] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1816test # [ 8.987749] iif2wwqra6cyixya934jn1gzixwi7l6v-wifi-secrets[581]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1817second # connecting to host...1818test # [ 9.003148] systemd[1]: Starting Post-Boot Actions...1819second # [ 8.981085] parport_pc 00:02: reported by Plug and Play ACPI1820second # [ 8.981175] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1821second # [ 9.011975] Console: switching to colour dummy device 80x251822second # [ 8.995552] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1823test # [ 9.022302] d1nj6zvw29gdp15fx855q5n4s3rj0h43-wifi-secrets[582]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1824second # [ 9.002651] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1825test # [ 9.041220] systemd[1]: Started Reset console on configuration changes.1826second # [ 9.046347] systemd[1]: Finished Post-Boot Actions.1827test # connecting to host...1828test # [ 9.078811] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1829second # [ 9.061267] systemd[1]: Started Name Service Cache Daemon (nsncd).1830test # [ 9.084505] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1831second # [ 9.063219] nsncd[564]: Sep 04 20:05:01.793 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1832test # [ 9.096317] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1833test # [ 9.103476] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1834second # [ 9.154157] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1835second # [ 9.224386] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:09.0/virtio7/input/input41836second # [ 9.087231] systemd[1]: Finished Import lastlog data into lastlog2 database.1837second # [ 9.090627] systemd[1]: Started Network Manager Script Dispatcher Service.1838test # [ 9.112860] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1839second # [ 9.094105] systemd[1]: Reached target Host and Network Name Lookups.1840test: Guest shell says: b'Spawning backdoor root shell...\n'1841test # [ 9.117213] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1842second # [ 9.096158] systemd[1]: Reached target User and Group Name Lookups.1843second # [ 9.106455] systemd[1]: Starting User Login Management...1844test: connected to guest root shell1845test: (connecting took 9.83 seconds)1846test: (finished: waiting for the VM to finish booting, in 9.83 seconds)1847test # [ 9.167608] systemd[1]: Finished Post-Boot Actions.1848second # [ 9.335144] [drm] Found bochs VGA, ID 0xb0c5.1849second # [ 9.335147] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1850second # [ 9.341536] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1851second # [ 9.342440] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01852second # [ 9.161126] systemd[1]: Starting Hostname Service...1853test # [ 9.197155] systemd[1]: Started Name Service Cache Daemon (nsncd).1854test # [ 9.198571] nsncd[592]: Sep 04 20:05:01.810 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1855test # [ 9.202131] systemd[1]: Started Network Manager Script Dispatcher Service.1856test # [ 9.212396] systemd[1]: Finished Import lastlog data into lastlog2 database.1857second # [ 9.208622] systemd-logind[599]: New seat seat0.1858second # [ 9.212773] systemd[1]: Started User Login Management.1859test # [ 9.239277] systemd[1]: Reached target Host and Network Name Lookups.1860second # [ 9.220310] systemd[1]: Starting linger-users.service...1861test # [ 9.242221] systemd[1]: Reached target User and Group Name Lookups.1862test # [ 9.253089] systemd[1]: Starting User Login Management...1863second # [ 9.423704] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1864second # [ 9.424565] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1865test # [ 9.267985] systemd[1]: Starting Hostname Service...1866test # [ 9.062073] rtc_cmos 00:04: registered as rtc01867test # [ 9.062175] rtc_cmos 00:04: setting system clock to 2026-09-04T20:05:01 UTC (1788552301)1868test # [ 9.062267] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1869second # [ 9.266963] systemd[1]: linger-users.service: Deactivated successfully.1870second # [ 9.269361] systemd[1]: Finished linger-users.service.1871second # [ 9.285324] systemd[1]: Started Hostname Service.1872test # [ 9.064825] systemd-journald[341]: Time jumped backwards, rotating.1873test # [ 9.073915] parport_pc 00:02: reported by Plug and Play ACPI1874test # [ 9.074018] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1875test # [ 9.140058] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:09.0/virtio7/input/input41876second # [ 9.481860] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61877second # [ 9.356911] systemd-networkd[509]: eth0: Link UP1878second # [ 9.358046] systemd-networkd[509]: eth0: Gained carrier1879test # [ 9.333787] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1880test # [ 9.454351] Console: switching to colour dummy device 80x251881test # [ 9.512286] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1882second # [ 9.380824] systemd[1]: Started Network Manager.1883second # [ 9.384430] systemd[1]: Reached target Network.1884second # [ 9.482157] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51885second # [ 9.389704] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1886second # [ 9.399601] systemd[1]: Starting Permit User Sessions...1887test # [ 9.512399] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1888test # [ 9.573321] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61889second # [ 9.500786] Console: switching to colour frame buffer device 160x501890second # [ 9.430671] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1891test # [ 9.573603] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51892test # [ 9.654753] [drm] Found bochs VGA, ID 0xb0c5.1893test # [ 9.654756] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1894test # [ 9.371476] systemd[1]: Started Hostname Service.1895test # [ 9.473662] systemd-networkd[532]: eth0: Link UP1896test # [ 9.474770] systemd-networkd[532]: eth0: Gained carrier1897test # [ 9.480335] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1898second # [ 9.460338] systemd[1]: Finished Permit User Sessions.1899test # [ 9.482258] systemd[1]: Started Network Manager.1900test # [ 9.486452] systemd[1]: Reached target Network.1901test # [ 9.487590] systemd-logind[638]: New seat seat0.1902second # [ 9.652946] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1903test # [ 9.489398] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1904second # [ 9.469833] systemd[1]: Started Getty on tty1.1905second # [ 9.470826] systemd[1]: Reached target Login Prompts.1906test # [ 9.493467] systemd[1]: Starting Permit User Sessions...1907test # [ 9.498684] systemd[1]: Started User Login Management.1908test # [ 9.695935] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1909test # [ 9.696778] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01910test # [ 9.532581] systemd[1]: Starting linger-users.service...1911test # [ 9.557500] systemd[1]: Starting Virtual Console Setup...1912second # [ 9.546413] systemd[1]: Starting Virtual Console Setup...1913test # [ 9.588652] systemd[1]: Finished Permit User Sessions.1914second # [ 9.583236] systemd-logind[599]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1915second # [ 9.588208] systemd-logind[599]: Watching system buttons on /dev/input/event2 (Power Button)1916second # [ 9.611608] systemd-resolved[348]: eth0: Bus client set default route setting: yes1917second # [ 9.623722] systemd-resolved[348]: eth0: Bus client set DNS server list to: 10.0.2.31918second # [ 9.627938] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1919second # [ 9.631417] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1920test # [ 9.655293] systemd-logind[638]: Watching system buttons on /dev/input/event2 (Power Button)1921test # [ 9.672437] systemd[1]: linger-users.service: Deactivated successfully.1922test # [ 9.676379] systemd[1]: Finished linger-users.service.1923second # [ 9.662693] systemd[1]: Reached target Multi-User System.1924second # [ 9.677600] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1925second # [ 9.681095] systemd[1]: Stopped Virtual Console Setup.1926test # [ 9.704849] systemd[1]: Started Getty on tty1.1927test # [ 9.707877] systemd[1]: Reached target Login Prompts.1928second # [ 9.696461] systemd[1]: Starting Virtual Console Setup...1929test # [ 9.725598] NetworkManager[583]: <warn> [1788552302.3456] keyfile: load: "/var/run/NetworkManager/system-connections/one.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1930test # [ 9.730419] NetworkManager[583]: <warn> [1788552302.3459] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1931test # [ 9.747137] systemd-logind[638]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1932second # [ 9.925372] ppdev: user-space parallel port driver1933test # [ 9.774275] systemd-resolved[369]: eth0: Bus client set default route setting: yes1934test # [ 9.780947] systemd-resolved[369]: eth0: Bus client set DNS server list to: 10.0.2.31935test # [ 9.800439] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1936test # [ 9.805253] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1937second # [ 9.979936] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1938test # [ 9.818166] systemd[1]: Reached target Multi-User System.1939second # [ 9.808137] systemd-logind[599]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1940second # [ 10.019955] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1941test # [ 10.069036] ppdev: user-space parallel port driver1942second # [ 9.892505] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1943test # [ 10.076819] Console: switching to colour frame buffer device 160x501944second # [ 9.897413] systemd[1]: Stopped Virtual Console Setup.1945test # [ 10.107736] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1946second # [ 9.904298] systemd[1]: Starting Virtual Console Setup...1947test # [ 9.937482] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1948test # [ 9.941519] systemd[1]: Stopped Virtual Console Setup.1949test # [ 9.954367] systemd[1]: Starting Virtual Console Setup...1950test # [ 9.964498] systemd-logind[638]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1951test # [ 10.155682] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1952second # [ 10.146315] kvm_amd: TSC scaling supported1953second # [ 10.146839] kvm_amd: Nested Virtualization enabled1954second # [ 10.147787] kvm_amd: Nested Paging enabled1955second # [ 10.148591] kvm_amd: LBR virtualization supported1956second # [ 10.149468] kvm_amd: Virtual GIF supported1957second # [ 10.150008] kvm_amd: Virtual NMI enabled1958test # [ 10.180364] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1959second # [ 10.183408] EDAC MC: Ver: 3.0.01960test # [ 10.054915] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1961test # [ 10.058892] systemd[1]: Stopped Virtual Console Setup.1962test # [ 10.072077] systemd[1]: Starting Virtual Console Setup...1963test # [ 10.300416] kvm_amd: TSC scaling supported1964test # [ 10.301087] kvm_amd: Nested Virtualization enabled1965test # [ 10.301704] kvm_amd: Nested Paging enabled1966test # [ 10.302629] kvm_amd: LBR virtualization supported1967test # [ 10.303671] kvm_amd: Virtual GIF supported1968test # [ 10.304919] kvm_amd: Virtual NMI enabled1969test # [ 10.338816] EDAC MC: Ver: 3.0.01970second # [ 10.133521] systemd-vconsole-setup[653]: Configuration of first virtual console was skipped, ignoring remaining ones.1971second # [ 10.137958] systemd[1]: Finished Virtual Console Setup.1972second # [ 10.142459] systemd[1]: Startup finished in 875ms (kernel) + 4.648s (initrd) + 4.616s (userspace) = 10.141s.1973test # [ 10.191381] systemd-networkd[532]: eth1: Gained IPv6LL1974test: (finished: waiting for unit NetworkManager.service, in 10.97 seconds)1975test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1976test # [ 10.287779] systemd-vconsole-setup[696]: Configuration of first virtual console was skipped, ignoring remaining ones.1977test # [ 10.292897] systemd[1]: Finished Virtual Console Setup.1978test # [ 10.297124] systemd[1]: Startup finished in 871ms (kernel) + 4.720s (initrd) + 4.704s (userspace) = 10.296s.1979test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.02 seconds)1980cleanup1981kill QemuMachine (pid 48)1982second # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/b5bpi6zfajzzrwwpgba2q6li3nnya4bs-python3-3.14.7/bin/python3.14)1983kill QemuMachine (pid 47)1984test # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/b5bpi6zfajzzrwwpgba2q6li3nnya4bs-python3-3.14.7/bin/python3.14)1985(finished: cleanup, in 0.21 seconds)1986Traceback (most recent call last):1987 File "/nix/store/1jbii2s79gzp4bdlgh9aqnaipayagrsh-nixos-test-driver-1.1/bin/.nixos-test-driver-wrapped", line 9, in <module>1988 sys.exit(main())1989 ~~~~^^1990 File "/nix/store/1jbii2s79gzp4bdlgh9aqnaipayagrsh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/__init__.py", line 194, in main1991 driver.run_tests()1992 ~~~~~~~~~~~~~~~~^^1993 File "/nix/store/1jbii2s79gzp4bdlgh9aqnaipayagrsh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/driver.py", line 437, in run_tests1994 self.test_script()1995 ~~~~~~~~~~~~~~~~^^1996 File "/nix/store/1jbii2s79gzp4bdlgh9aqnaipayagrsh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/driver.py", line 395, in test_script1997 exec(self.tests, symbols, None)1998 ~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^1999 File "<string>", line 4, in <module>2000AssertionError: Expected mock password in connection file:2001[connection]2002autoconnect=true2003id=2004type=wifi20052006[wifi]2007mode=infrastructure2008ssid=20092010[wifi-security]2011key-mgmt=wpa-psk2012psk=2013