vm-test-run-wifi
checks.x86_64-linux.wifi
· build #230
· raw
1gi-build-x86-02: building 2azmpm41k10fl74c137ydl0qxlj40dgz-vm-test-run-wifi.drv2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7additionally exposed symbols:8 second, test,9 vlan1,10 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh11start all VMs12second: starting vm13test: starting vm14second # Disk image does not exist, creating the virtualisation disk image...15second # Formatting '/build/vm-state-second/tmp.lcLgHjYygH', fmt=raw size=107374182416second: QEMU running (pid 48)17second # mke2fs 1.47.4 (6-Mar-2025)18second # Discarding device blocks: 0/262144 done19second # Creating filesystem with 262144 4k blocks and 65536 inodes20second # Filesystem UUID: 0b7dc441-2b25-4d9c-baa1-09bbbfcdbd1e21second # Superblock backups stored on blocks:22second # 32768, 98304, 163840, 22937623second # 24second # Allocating group tables: 0/8 done25second # Writing inode tables: 0/8 done26second # Creating journal (8192 blocks): done27second # Writing superblocks and filesystem accounting information: 0/8 done28second # 29second # Virtualisation disk image created.30second # Starting virtiofs daemons...31second # [2026-10-05T20:13:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)32second # [2026-10-05T20:13:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether33second # [2026-10-05T20:13:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)34second # [2026-10-05T20:13:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether35second # [2026-10-05T20:13:10Z INFO virtiofsd] Waiting for vhost-user socket connection...36second # [2026-10-05T20:13:10Z INFO virtiofsd] Waiting for vhost-user socket connection...37second # [2026-10-05T20:13:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)38second # [2026-10-05T20:13:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether39second # [2026-10-05T20:13:10Z INFO virtiofsd] Waiting for vhost-user socket connection...40second # [2026-10-05T20:13:10Z INFO virtiofsd] Client connected, servicing requests41second # [2026-10-05T20:13:10Z INFO virtiofsd] Client connected, servicing requests42second # [2026-10-05T20:13:10Z INFO virtiofsd] Client connected, servicing requests43test # Disk image does not exist, creating the virtualisation disk image...44test # Formatting '/build/vm-state-test/tmp.4jK9eJTEQR', fmt=raw size=107374182445test # mke2fs 1.47.4 (6-Mar-2025)46test # Discarding device blocks: 0/262144 done47test # Creating filesystem with 262144 4k blocks and 65536 inodes48test # Filesystem UUID: caffa482-4a41-4548-b030-662c2be8f08a49test # Superblock backups stored on blocks:50test # 32768, 98304, 163840, 22937651test # 52test # Allocating group tables: 0/8 done53test # Writing inode tables: 0/8 done54test # Creating journal (8192 blocks): done55test # Writing superblocks and filesystem accounting information: 0/8 done56test # 57test # Virtualisation disk image created.58test # Starting virtiofs daemons...59test # [2026-10-05T20:13:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)60test # [2026-10-05T20:13:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether61test # [2026-10-05T20:13:10Z INFO virtiofsd] Waiting for vhost-user socket connection...62test # [2026-10-05T20:13:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)63test # [2026-10-05T20:13:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether64test # [2026-10-05T20:13:10Z INFO virtiofsd] Waiting for vhost-user socket connection...65test # [2026-10-05T20:13:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)66test # [2026-10-05T20:13:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether67test # [2026-10-05T20:13:10Z INFO virtiofsd] Waiting for vhost-user socket connection...68test # [2026-10-05T20:13:10Z INFO virtiofsd] Client connected, servicing requests69test: QEMU running (pid 47)70test # [2026-10-05T20:13:10Z INFO virtiofsd] Client connected, servicing requests71test # [2026-10-05T20:13:10Z INFO virtiofsd] Client connected, servicing requests72(finished: start all VMs, in 0.37 seconds)73test: waiting for unit NetworkManager.service74test: waiting for the VM to finish booting75second # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)76test # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)77second # 78second # 79test # 80test # 81second # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0082test # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0083second # Press Ctrl-B to configure iPXE (PCI 00:02.0)...84second # 85second # 86second # 87second # 88test # Press Ctrl-B to configure iPXE (PCI 00:02.0)...89test # 90test # 91test # 92test # 93second # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0094test # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0095second # Press Ctrl-B to configure iPXE (PCI 00:05.0)...96second # 97second # 98test # Press Ctrl-B to configure iPXE (PCI 00:05.0)...99test # 100test # 101second # Booting from ROM...102test # Booting from ROM...103second # Probing EDD (edd=off to disable)... o[ 0.000000] Linux version 6.18.55 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Sat Oct 3 10:39:03 UTC 2026104test # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.55 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Sat Oct 3 10:39:03 UTC 2026105second # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/0l1qpij3z4s5isdrdf49vcs4j4lr9zf4-nixos-system-second-test/init regInfo=/nix/.ro-store/4jjypkkba961iv1haf7vysq8jwczd6h4-closure-info/registration console=ttyS0,115200n8 console=tty0106second # [ 0.000000] BIOS-provided physical RAM map:107test # [ 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/waww6jdj07q1nj13pp56h32sb9rb3834-nixos-system-test-test/init regInfo=/nix/.ro-store/pxq40fc0anjnlc7b1jsv9y25r91jmac1-closure-info/registration console=ttyS0,115200n8 console=tty0108second # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable109test # [ 0.000000] BIOS-provided physical RAM map:110second # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved111test # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable112second # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved113test # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved114second # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable115test # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved116second # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved117test # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable118second # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved119test # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved120second # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved121test # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved122second # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved123test # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved124second # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved125test # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved126second # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved127test # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved128second # [ 0.000000] NX (Execute Disable) protection: active129test # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved130second # [ 0.000000] APIC: Static calls initialized131test # [ 0.000000] NX (Execute Disable) protection: active132second # [ 0.000000] SMBIOS 2.8 present.133test # [ 0.000000] APIC: Static calls initialized134test # [ 0.000000] SMBIOS 2.8 present.135second # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014136second # [ 0.000000] DMI: Memory slots populated: 1/1137test # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014138second # [ 0.000000] Hypervisor detected: KVM139test # [ 0.000000] DMI: Memory slots populated: 1/1140second # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000141test # [ 0.000000] Hypervisor detected: KVM142second # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00143test # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000144second # [ 0.000000] kvm-clock: using sched offset of 388510537 cycles145test # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00146test # [ 0.000000] kvm-clock: using sched offset of 388885943 cycles147second # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns148second # [ 0.000004] tsc: Detected 4192.088 MHz processor149test # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns150second # [ 0.000567] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000151test # [ 0.000004] tsc: Detected 4192.088 MHz processor152test # [ 0.000562] last_pfn = 0x3ffd8 max_arch_pfn = 0x400000000153second # [ 0.000595] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs154test # [ 0.000590] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs155second # [ 0.000597] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT156second # [ 0.002235] found SMP MP-table at [mem 0x000f5450-0x000f545f]157test # [ 0.000592] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT158second # [ 0.002247] Using GB pages for direct mapping159test # [ 0.002244] found SMP MP-table at [mem 0x000f5450-0x000f545f]160second # [ 0.002326] RAMDISK: [mem 0x3e2e6000-0x3ffcffff]161test # [ 0.002256] Using GB pages for direct mapping162second # [ 0.002330] ACPI: Early table checksum verification disabled163test # [ 0.002347] RAMDISK: [mem 0x3e2e6000-0x3ffcffff]164second # [ 0.002333] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )165test # [ 0.002352] ACPI: Early table checksum verification disabled166test # [ 0.002354] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )167second # [ 0.002336] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)168test # [ 0.002357] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)169second # [ 0.002340] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)170test # [ 0.002360] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)171second # [ 0.002344] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)172second # [ 0.002346] ACPI: FACS 0x000000003FFE0000 000040173test # [ 0.002365] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)174test # [ 0.002367] ACPI: FACS 0x000000003FFE0000 000040175second # [ 0.002347] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)176test # [ 0.002368] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)177second # [ 0.002348] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)178test # [ 0.002369] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)179second # [ 0.002349] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)180test # [ 0.002370] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)181second # [ 0.002351] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)182test # [ 0.002371] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)183second # [ 0.002352] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]184test # [ 0.002372] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]185second # [ 0.002352] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]186test # [ 0.002373] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]187second # [ 0.002353] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]188test # [ 0.002374] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]189second # [ 0.002353] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]190test # [ 0.002374] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]191second # [ 0.002354] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]192test # [ 0.002374] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]193second # [ 0.002354] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]194test # [ 0.002375] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]195second # [ 0.002354] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]196second # [ 0.002703] No NUMA configuration found197test # [ 0.002375] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]198second # [ 0.002704] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]199test # [ 0.002721] No NUMA configuration found200second # [ 0.002706] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]201test # [ 0.002722] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]202second # [ 0.002771] Zone ranges:203test # [ 0.002725] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]204second # [ 0.002772] DMA [mem 0x0000000000001000-0x0000000000ffffff]205test # [ 0.002790] Zone ranges:206second # [ 0.002773] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]207test # [ 0.002790] DMA [mem 0x0000000000001000-0x0000000000ffffff]208second # [ 0.002774] Normal empty209second # [ 0.002774] Device empty210test # [ 0.002792] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]211test # [ 0.002792] Normal empty212second # [ 0.002775] Movable zone start for each node213test # [ 0.002793] Device empty214second # [ 0.002775] Early memory node ranges215test # [ 0.002793] Movable zone start for each node216second # [ 0.002775] node 0: [mem 0x0000000000001000-0x000000000009efff]217test # [ 0.002794] Early memory node ranges218second # [ 0.002776] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]219test # [ 0.002794] node 0: [mem 0x0000000000001000-0x000000000009efff]220test # [ 0.002795] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]221second # [ 0.002777] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]222second # [ 0.002793] On node 0, zone DMA: 1 pages in unavailable ranges223test # [ 0.002796] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]224second # [ 0.002978] On node 0, zone DMA: 97 pages in unavailable ranges225test # [ 0.003035] On node 0, zone DMA: 1 pages in unavailable ranges226second # [ 0.015297] On node 0, zone DMA32: 40 pages in unavailable ranges227test # [ 0.003220] On node 0, zone DMA: 97 pages in unavailable ranges228second # [ 0.016083] ACPI: PM-Timer IO Port: 0x608229test # [ 0.015521] On node 0, zone DMA32: 40 pages in unavailable ranges230second # [ 0.016092] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])231test # [ 0.016308] ACPI: PM-Timer IO Port: 0x608232test # [ 0.016316] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])233second # [ 0.016112] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23234test # [ 0.016335] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23235second # [ 0.016114] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)236test # [ 0.016337] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)237second # [ 0.016115] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)238test # [ 0.016339] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)239second # [ 0.016116] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)240test # [ 0.016339] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)241second # [ 0.016116] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)242test # [ 0.016340] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)243second # [ 0.016117] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)244test # [ 0.016340] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)245second # [ 0.016119] ACPI: Using ACPI (MADT) for SMP configuration information246second # [ 0.016119] ACPI: HPET id: 0x8086a201 base: 0xfed00000247test # [ 0.016342] ACPI: Using ACPI (MADT) for SMP configuration information248second # [ 0.016122] TSC deadline timer available249test # [ 0.016343] ACPI: HPET id: 0x8086a201 base: 0xfed00000250second # [ 0.016125] CPU topo: Max. logical packages: 1251test # [ 0.016345] TSC deadline timer available252second # [ 0.016125] CPU topo: Max. logical dies: 1253test # [ 0.016348] CPU topo: Max. logical packages: 1254second # [ 0.016126] CPU topo: Max. dies per package: 1255test # [ 0.016349] CPU topo: Max. logical dies: 1256second # [ 0.016128] CPU topo: Max. threads per core: 1257test # [ 0.016349] CPU topo: Max. dies per package: 1258second # [ 0.016129] CPU topo: Num. cores per package: 1259test # [ 0.016352] CPU topo: Max. threads per core: 1260second # [ 0.016129] CPU topo: Num. threads per package: 1261test # [ 0.016352] CPU topo: Num. cores per package: 1262second # [ 0.016129] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs263test # [ 0.016353] CPU topo: Num. threads per package: 1264test # [ 0.016353] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs265second # [ 0.016141] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()266test # [ 0.016365] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()267second # [ 0.016166] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]268test # [ 0.016389] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]269second # [ 0.016167] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]270second # [ 0.016168] [mem 0x40000000-0xafffffff] available for PCI devices271test # [ 0.016391] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]272second # [ 0.016169] Booting paravirtualized kernel on KVM273test # [ 0.016392] [mem 0x40000000-0xafffffff] available for PCI devices274test # [ 0.016393] Booting paravirtualized kernel on KVM275second # [ 0.016171] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns276test # [ 0.016395] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns277second # [ 0.019598] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1278test # [ 0.019815] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1279second # [ 0.021161] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152280second # [ 0.021191] kvm-guest: PV spinlocks disabled, single CPU281test # [ 0.021379] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152282test # [ 0.021408] kvm-guest: PV spinlocks disabled, single CPU283second # [ 0.021192] 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/0l1qpij3z4s5isdrdf49vcs4j4lr9zf4-nixos-system-second-test/init regInfo=/nix/.ro-store/4jjypkkba961iv1haf7vysq8jwczd6h4-closure-info/registration console=ttyS0,115200n8 console=tty0284test # [ 0.021409] 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/waww6jdj07q1nj13pp56h32sb9rb3834-nixos-system-test-test/init regInfo=/nix/.ro-store/pxq40fc0anjnlc7b1jsv9y25r91jmac1-closure-info/registration console=ttyS0,115200n8 console=tty0285second # [ 0.021260] Unknown kernel command line parameters "regInfo=/nix/.ro-store/4jjypkkba961iv1haf7vysq8jwczd6h4-closure-info/registration", will be passed to user space.286second # [ 0.021450] random: crng init done287test # [ 0.021478] Unknown kernel command line parameters "regInfo=/nix/.ro-store/pxq40fc0anjnlc7b1jsv9y25r91jmac1-closure-info/registration", will be passed to user space.288test # [ 0.021668] random: crng init done289second # [ 0.021450] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes290test # [ 0.021669] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes291second # [ 0.021466] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)292test # [ 0.021686] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)293second # [ 0.021856] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)294second # [ 0.021880] Fallback order for Node 0: 0295test # [ 0.022087] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)296test # [ 0.022115] Fallback order for Node 0: 0297second # [ 0.021882] Built 1 zonelists, mobility grouping on. Total pages: 262006298second # [ 0.021883] Policy zone: DMA32299test # [ 0.022117] Built 1 zonelists, mobility grouping on. Total pages: 262006300test # [ 0.022118] Policy zone: DMA32301second # [ 0.023648] mem auto-init: stack:all(zero), heap alloc:on, heap free:off302test # [ 0.023990] mem auto-init: stack:all(zero), heap alloc:on, heap free:off303second # [ 0.025905] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1304second # [ 0.027657] allocated 2097152 bytes of page_ext305test # [ 0.026329] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1306second # [ 0.036221] ftrace: allocating 48837 entries in 192 pages307test # [ 0.027927] allocated 2097152 bytes of page_ext308second # [ 0.036223] ftrace: allocated 192 pages with 2 groups309test # [ 0.036478] ftrace: allocating 48837 entries in 192 pages310second # [ 0.036878] Dynamic Preempt: lazy311test # [ 0.036479] ftrace: allocated 192 pages with 2 groups312second # [ 0.037005] rcu: Preemptible hierarchical RCU implementation.313test # [ 0.037104] Dynamic Preempt: lazy314second # [ 0.037006] rcu: RCU event tracing is enabled.315test # [ 0.037224] rcu: Preemptible hierarchical RCU implementation.316test # [ 0.037225] rcu: RCU event tracing is enabled.317second # [ 0.037006] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.318second # [ 0.037007] Trampoline variant of Tasks RCU enabled.319test # [ 0.037225] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.320second # [ 0.037008] Rude variant of Tasks RCU enabled.321test # [ 0.037226] Trampoline variant of Tasks RCU enabled.322second # [ 0.037008] Tracing variant of Tasks RCU enabled.323test # [ 0.037227] Rude variant of Tasks RCU enabled.324test # [ 0.037227] Tracing variant of Tasks RCU enabled.325second # [ 0.037008] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.326test # [ 0.037227] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.327second # [ 0.037009] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1328test # [ 0.037228] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1329second # [ 0.037022] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.330test # [ 0.037244] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.331second # [ 0.037023] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.332test # [ 0.037245] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.333second # [ 0.037024] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.334second # [ 0.040198] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16335test # [ 0.037245] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.336test # [ 0.040408] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16337second # [ 0.040440] rcu: srcu_init: Setting srcu_struct sizes based on contention.338test # [ 0.040649] rcu: srcu_init: Setting srcu_struct sizes based on contention.339second # [ 0.040446] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns340test # [ 0.040653] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns341second # [ 0.040603] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)342second # [ 0.045802] Console: colour VGA+ 80x25343test # [ 0.040804] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)344second # [ 0.045805] printk: legacy console [tty0] enabled345test # [ 0.045990] Console: colour VGA+ 80x25346second # [ 0.076963] printk: legacy console [ttyS0] enabled347test # [ 0.045993] printk: legacy console [tty0] enabled348second # [ 0.212361] ACPI: Core revision 20250807349test # [ 0.076567] printk: legacy console [ttyS0] enabled350test # [ 0.211422] ACPI: Core revision 20250807351second # [ 0.213464] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns352test # [ 0.212519] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns353second # [ 0.215401] APIC: Switch to symmetric I/O mode setup354second # [ 0.216587] x2apic enabled355test # [ 0.214433] APIC: Switch to symmetric I/O mode setup356test # [ 0.215625] x2apic enabled357second # [ 0.217407] APIC: Switched APIC routing to: physical x2apic358test # [ 0.216444] APIC: Switched APIC routing to: physical x2apic359second # [ 0.219366] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1360test # [ 0.218401] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1361second # [ 0.220605] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3c6d2fa06f4, max_idle_ns: 440795322535 ns362test # [ 0.219652] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3c6d2fa06f4, max_idle_ns: 440795322535 ns363second # [ 0.222685] Calibrating delay loop (skipped) preset value.. 8384.17 BogoMIPS (lpj=4192088)364test # [ 0.221742] Calibrating delay loop (skipped) preset value.. 8384.17 BogoMIPS (lpj=4192088)365second # [ 0.224753] x86/cpu: User Mode Instruction Prevention (UMIP) activated366test # [ 0.223805] x86/cpu: User Mode Instruction Prevention (UMIP) activated367second # [ 0.225788] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127368test # [ 0.224847] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127369second # [ 0.226684] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0370test # [ 0.225741] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0371second # [ 0.227687] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto372test # [ 0.226744] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto373second # [ 0.229684] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl374second # [ 0.230684] Spectre V2 : Mitigation: Enhanced / Automatic IBRS375test # [ 0.227742] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl376second # [ 0.232684] Speculative Return Stack Overflow: Mitigation: Safe RET377test # [ 0.228741] Spectre V2 : Mitigation: Enhanced / Automatic IBRS378test # [ 0.229741] Speculative Return Stack Overflow: Mitigation: Safe RET379second # [ 0.233684] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization380test # [ 0.230741] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization381second # [ 0.234690] Spectre V2 : Enabling IBPB for BPF382test # [ 0.231746] Spectre V2 : Enabling IBPB for BPF383second # [ 0.236685] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier384second # [ 0.238685] active return thunk: srso_alias_return_thunk385test # [ 0.233469] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier386test # [ 0.234741] active return thunk: srso_alias_return_thunk387second # [ 0.239705] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'388test # [ 0.235762] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'389second # [ 0.240684] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'390test # [ 0.237741] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'391second # [ 0.241684] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'392test # [ 0.238741] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'393second # [ 0.242684] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'394test # [ 0.239741] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'395second # [ 0.243684] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'396test # [ 0.241741] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'397second # [ 0.245684] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'398test # [ 0.242741] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'399second # [ 0.246684] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'400test # [ 0.243741] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'401second # [ 0.247684] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'402test # [ 0.244741] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'403second # [ 0.248684] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'404test # [ 0.245741] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'405second # [ 0.249685] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256406test # [ 0.247741] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256407second # [ 0.250684] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64408test # [ 0.248741] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64409second # [ 0.251684] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512410test # [ 0.250740] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512411second # [ 0.253684] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024412test # [ 0.251741] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024413second # [ 0.254684] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8414test # [ 0.252741] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8415second # [ 0.255684] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16416test # [ 0.253741] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16417second # [ 0.256684] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24418test # [ 0.254741] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24419second # [ 0.258684] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format.420test # [ 0.256740] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format.421second # [ 0.284268] Freeing SMP alternatives memory: 48K422second # [ 0.284686] pid_max: default: 32768 minimum: 301423test # [ 0.282436] Freeing SMP alternatives memory: 48K424test # [ 0.282743] pid_max: default: 32768 minimum: 301425second # [ 0.285749] LSM: initializing lsm=capability,landlock,yama,bpf,ima426second # [ 0.286768] landlock: Up and running.427test # [ 0.283798] LSM: initializing lsm=capability,landlock,yama,bpf,ima428second # [ 0.288281] Yama: becoming mindful.429test # [ 0.285788] landlock: Up and running.430second # [ 0.288859] LSM support for eBPF active431test # [ 0.286570] Yama: becoming mindful.432test # [ 0.287483] LSM support for eBPF active433second # [ 0.289754] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)434test # [ 0.287824] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)435second # [ 0.290701] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)436test # [ 0.288759] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)437second # [ 0.293030] smpboot: CPU0: AMD Ryzen 9 7950X3D 16-Core Processor (family: 0x19, model: 0x61, stepping: 0x2)438test # [ 0.291245] smpboot: CPU0: AMD Ryzen 9 7950X3D 16-Core Processor (family: 0x19, model: 0x61, stepping: 0x2)439second # [ 0.294090] Performance Events: Fam17h+ core perfctr, AMD PMU driver.440second # [ 0.294688] ... version: 2441test # [ 0.292111] Performance Events: Fam17h+ core perfctr, AMD PMU driver.442second # [ 0.295579] ... bit width: 48443test # [ 0.292744] ... version: 2444second # [ 0.296391] ... generic counters: 6445test # [ 0.293630] ... bit width: 48446second # [ 0.296692] ... generic bitmap: 000000000000003f447test # [ 0.294442] ... generic counters: 6448second # [ 0.297685] ... fixed-purpose counters: 0449test # [ 0.294781] ... generic bitmap: 000000000000003f450second # [ 0.298586] ... fixed-purpose bitmap: 0000000000000000451test # [ 0.295742] ... fixed-purpose counters: 0452second # [ 0.299591] ... value mask: 0000ffffffffffff453test # [ 0.296629] ... fixed-purpose bitmap: 0000000000000000454second # [ 0.300580] ... max period: 00007fffffffffff455test # [ 0.297633] ... value mask: 0000ffffffffffff456second # [ 0.301588] ... global_ctrl mask: 000000000000003f457test # [ 0.298651] ... max period: 00007fffffffffff458second # [ 0.302675] signal: max sigframe size: 3376459test # [ 0.299648] ... global_ctrl mask: 000000000000003f460second # [ 0.303439] rcu: Hierarchical SRCU implementation.461test # [ 0.300726] signal: max sigframe size: 3376462second # [ 0.303689] rcu: Max phase no-delay instances is 400.463test # [ 0.301510] rcu: Hierarchical SRCU implementation.464test # [ 0.301745] rcu: Max phase no-delay instances is 400.465second # [ 0.307720] smp: Bringing up secondary CPUs ...466second # [ 0.308672] smp: Brought up 1 node, 1 CPU467test # [ 0.305779] smp: Bringing up secondary CPUs ...468test # [ 0.306731] smp: Brought up 1 node, 1 CPU469second # [ 0.309348] smpboot: Total of 1 processors activated (8384.17 BogoMIPS)470test # [ 0.307413] smpboot: Total of 1 processors activated (8384.17 BogoMIPS)471second # [ 0.309815] Memory: 942472K/1048024K available (18124K kernel code, 2728K rwdata, 13644K rodata, 3656K init, 3632K bss, 98108K reserved, 0K cma-reserved)472second # [ 0.310847] devtmpfs: initialized473test # [ 0.307876] Memory: 942496K/1048024K available (18124K kernel code, 2728K rwdata, 13644K rodata, 3656K init, 3632K bss, 98108K reserved, 0K cma-reserved)474second # [ 0.311751] x86/mm: Memory block size: 128MB475test # [ 0.308903] devtmpfs: initialized476test # [ 0.309831] x86/mm: Memory block size: 128MB477second # [ 0.313332] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)478test # [ 0.311449] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)479second # [ 0.313707] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).480second # [ 0.314774] pinctrl core: initialized pinctrl subsystem481test # [ 0.311771] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).482second # [ 0.315951] PM: RTC time: 20:13:10, date: 2026-10-05483test # [ 0.312812] pinctrl core: initialized pinctrl subsystem484test # [ 0.314000] PM: RTC time: 20:13:10, date: 2026-10-05485second # [ 0.318574] NET: Registered PF_NETLINK/PF_ROUTE protocol family486test # [ 0.316721] NET: Registered PF_NETLINK/PF_ROUTE protocol family487second # [ 0.319927] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations488test # [ 0.318000] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations489second # [ 0.320700] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations490test # [ 0.318757] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations491second # [ 0.321795] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations492second # [ 0.322693] audit: initializing netlink subsys (disabled)493test # [ 0.319846] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations494test # [ 0.320750] audit: initializing netlink subsys (disabled)495second # [ 0.323909] thermal_sys: Registered thermal governor 'fair_share'496second # [ 0.323910] thermal_sys: Registered thermal governor 'bang_bang'497test # [ 0.321938] thermal_sys: Registered thermal governor 'fair_share'498second # [ 0.324686] thermal_sys: Registered thermal governor 'step_wise'499test # [ 0.321940] thermal_sys: Registered thermal governor 'bang_bang'500second # [ 0.325689] audit: type=2000 audit(1791231190.879:1): state=initialized audit_enabled=0 res=1501test # [ 0.322745] audit: type=2000 audit(1791231190.880:1): state=initialized audit_enabled=0 res=1502second # [ 0.327687] thermal_sys: Registered thermal governor 'user_space'503test # [ 0.324744] thermal_sys: Registered thermal governor 'step_wise'504second # [ 0.327689] thermal_sys: Registered thermal governor 'power_allocator'505test # [ 0.324745] thermal_sys: Registered thermal governor 'user_space'506second # [ 0.328700] cpuidle: using governor menu507test # [ 0.325742] thermal_sys: Registered thermal governor 'power_allocator'508test # [ 0.326756] cpuidle: using governor menu509second # [ 0.331366] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5510test # [ 0.329420] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5511second # [ 0.331925] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]512test # [ 0.329972] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]513second # [ 0.332688] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry514second # [ 0.333696] PCI: Using configuration type 1 for base access515test # [ 0.330744] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry516test # [ 0.331752] PCI: Using configuration type 1 for base access517second # [ 0.334841] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.518test # [ 0.332893] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.519second # [ 0.341818] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages520test # [ 0.339763] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages521second # [ 0.342686] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page522test # [ 0.340742] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page523second # [ 0.347685] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages524test # [ 0.345742] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages525second # [ 0.348687] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page526test # [ 0.346743] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page527test # [ 0.353670] ACPI: Added _OSI(Module Device)528test # [ 0.354425] ACPI: Added _OSI(Processor Device)529second # [ 0.357998] ACPI: Added _OSI(Module Device)530test # [ 0.354742] ACPI: Added _OSI(Processor Aggregator Device)531second # [ 0.358686] ACPI: Added _OSI(Processor Device)532second # [ 0.359632] ACPI: Added _OSI(Processor Aggregator Device)533second # [ 0.364547] ACPI: 1 ACPI AML tables successfully acquired and loaded534test # [ 0.361625] ACPI: 1 ACPI AML tables successfully acquired and loaded535second # [ 0.369135] ACPI: Interpreter enabled536test # [ 0.365561] ACPI: Interpreter enabled537test # [ 0.366358] ACPI: PM: (supports S0 S3 S4 S5)538second # [ 0.369701] ACPI: PM: (supports S0 S3 S4 S5)539test # [ 0.366743] ACPI: Using IOAPIC for interrupt routing540second # [ 0.370624] ACPI: Using IOAPIC for interrupt routing541second # [ 0.371536] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug542test # [ 0.368960] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug543second # [ 0.372686] PCI: Using E820 reservations for host bridge windows544test # [ 0.369742] PCI: Using E820 reservations for host bridge windows545second # [ 0.373818] ACPI: Enabled 2 GPEs in block 00 to 3F546test # [ 0.370856] ACPI: Enabled 2 GPEs in block 00 to 3F547second # [ 0.377918] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])548test # [ 0.374923] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])549second # [ 0.378692] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]550test # [ 0.375747] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]551second # [ 0.379763] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]552test # [ 0.376802] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]553second # [ 0.380796] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]554test # [ 0.377838] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]555second # [ 0.382105] PCI host bridge to bus 0000:00556test # [ 0.379140] PCI host bridge to bus 0000:00557second # [ 0.382692] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]558test # [ 0.379746] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]559second # [ 0.383688] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]560test # [ 0.380743] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]561second # [ 0.384687] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]562test # [ 0.381749] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]563second # [ 0.385687] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]564test # [ 0.382743] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]565second # [ 0.386687] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]566test # [ 0.383749] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]567second # [ 0.387687] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]568test # [ 0.384743] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]569second # [ 0.388688] pci_bus 0000:00: root bus resource [bus 00-ff]570test # [ 0.385743] pci_bus 0000:00: root bus resource [bus 00-ff]571second # [ 0.389775] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint572test # [ 0.386833] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint573second # [ 0.391336] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint574test # [ 0.388391] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint575second # [ 0.393637] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]576test # [ 0.390697] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]577second # [ 0.394706] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]578test # [ 0.391762] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]579second # [ 0.395731] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]580test # [ 0.392780] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]581second # [ 0.396873] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]582test # [ 0.393906] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]583second # [ 0.398456] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint584test # [ 0.395477] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint585second # [ 0.400697] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]586test # [ 0.397753] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]587second # [ 0.401768] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]588test # [ 0.398753] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]589second # [ 0.402740] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]590test # [ 0.399780] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]591second # [ 0.403753] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]592test # [ 0.400800] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]593second # [ 0.405715] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint594test # [ 0.402762] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint595second # [ 0.407697] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]596test # [ 0.405377] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]597second # [ 0.408697] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]598test # [ 0.405753] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]599second # [ 0.409730] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]600test # [ 0.406780] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]601second # [ 0.411570] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint602test # [ 0.408619] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint603test # [ 0.410753] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]604second # [ 0.414696] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]605test # [ 0.411753] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]606second # [ 0.415699] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]607test # [ 0.412781] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]608second # [ 0.416730] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]609test # [ 0.414604] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint610second # [ 0.418576] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint611test # [ 0.416753] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]612second # [ 0.420696] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]613test # [ 0.417753] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]614second # [ 0.421698] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]615test # [ 0.418780] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]616second # [ 0.422731] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]617test # [ 0.419752] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]618second # [ 0.423699] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]619test # [ 0.421623] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint620second # [ 0.425561] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint621test # [ 0.423578] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]622second # [ 0.427560] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]623test # [ 0.424739] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]624second # [ 0.428685] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]625test # [ 0.426649] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint626second # [ 0.430553] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint627test # [ 0.428924] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]628second # [ 0.432543] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]629test # [ 0.429781] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]630second # [ 0.433679] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]631test # [ 0.431649] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint632second # [ 0.435626] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint633test # [ 0.433561] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]634second # [ 0.437696] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]635test # [ 0.434731] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]636second # [ 0.438730] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]637test # [ 0.436615] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint638second # [ 0.440570] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint639test # [ 0.438587] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]640second # [ 0.442508] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]641test # [ 0.439742] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]642second # [ 0.443686] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]643test # [ 0.441608] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint644second # [ 0.445566] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint645test # [ 0.443753] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]646second # [ 0.447892] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]647test # [ 0.444753] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]648second # [ 0.448698] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]649test # [ 0.445781] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]650second # [ 0.449727] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]651test # [ 0.447598] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint652second # [ 0.451554] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint653test # [ 0.449753] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]654second # [ 0.453696] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]655test # [ 0.450753] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]656second # [ 0.454696] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]657test # [ 0.451780] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]658second # [ 0.455724] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]659test # [ 0.453785] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint660second # [ 0.457589] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint661test # [ 0.455387] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]662second # [ 0.459313] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]663test # [ 0.456006] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint664second # [ 0.459938] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint665test # [ 0.458394] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]666second # [ 0.462331] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]667test # [ 0.459009] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint668second # [ 0.462935] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint669test # [ 0.460400] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]670second # [ 0.464352] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]671test # [ 0.461074] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint672second # [ 0.465098] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint673test # [ 0.462358] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]674second # [ 0.466291] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]675test # [ 0.463041] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint676second # [ 0.467024] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint677test # [ 0.464233] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO678second # [ 0.468162] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO679test # [ 0.465084] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint680second # [ 0.469018] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint681test # [ 0.466622] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]682second # [ 0.470639] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]683second # [ 0.471553] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]684test # [ 0.467610] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]685second # [ 0.473140] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint686test # [ 0.469283] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint687second # [ 0.474336] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]688test # [ 0.470456] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]689second # [ 0.479399] ACPI: PCI: Interrupt link LNKA configured for IRQ 10690test # [ 0.476429] ACPI: PCI: Interrupt link LNKA configured for IRQ 10691second # [ 0.479803] ACPI: PCI: Interrupt link LNKB configured for IRQ 10692test # [ 0.476870] ACPI: PCI: Interrupt link LNKB configured for IRQ 10693second # [ 0.480801] ACPI: PCI: Interrupt link LNKC configured for IRQ 11694test # [ 0.477864] ACPI: PCI: Interrupt link LNKC configured for IRQ 11695second # [ 0.481800] ACPI: PCI: Interrupt link LNKD configured for IRQ 11696test # [ 0.478863] ACPI: PCI: Interrupt link LNKD configured for IRQ 11697second # [ 0.482798] ACPI: PCI: Interrupt link LNKE configured for IRQ 10698test # [ 0.479863] ACPI: PCI: Interrupt link LNKE configured for IRQ 10699second # [ 0.483801] ACPI: PCI: Interrupt link LNKF configured for IRQ 10700test # [ 0.480860] ACPI: PCI: Interrupt link LNKF configured for IRQ 10701second # [ 0.484801] ACPI: PCI: Interrupt link LNKG configured for IRQ 11702test # [ 0.481864] ACPI: PCI: Interrupt link LNKG configured for IRQ 11703second # [ 0.485824] ACPI: PCI: Interrupt link LNKH configured for IRQ 11704test # [ 0.482898] ACPI: PCI: Interrupt link LNKH configured for IRQ 11705second # [ 0.486736] ACPI: PCI: Interrupt link GSIA configured for IRQ 16706test # [ 0.483796] ACPI: PCI: Interrupt link GSIA configured for IRQ 16707second # [ 0.487702] ACPI: PCI: Interrupt link GSIB configured for IRQ 17708second # [ 0.488702] ACPI: PCI: Interrupt link GSIC configured for IRQ 18709test # [ 0.484761] ACPI: PCI: Interrupt link GSIB configured for IRQ 17710second # [ 0.489702] ACPI: PCI: Interrupt link GSID configured for IRQ 19711test # [ 0.485763] ACPI: PCI: Interrupt link GSIC configured for IRQ 18712second # [ 0.490698] ACPI: PCI: Interrupt link GSIE configured for IRQ 20713test # [ 0.486764] ACPI: PCI: Interrupt link GSID configured for IRQ 19714second # [ 0.491699] ACPI: PCI: Interrupt link GSIF configured for IRQ 21715test # [ 0.487764] ACPI: PCI: Interrupt link GSIE configured for IRQ 20716second # [ 0.492705] ACPI: PCI: Interrupt link GSIG configured for IRQ 22717test # [ 0.488764] ACPI: PCI: Interrupt link GSIF configured for IRQ 21718second # [ 0.493703] ACPI: PCI: Interrupt link GSIH configured for IRQ 23719test # [ 0.489797] ACPI: PCI: Interrupt link GSIG configured for IRQ 22720second # [ 0.495508] iommu: Default domain type: Translated721test # [ 0.490757] ACPI: PCI: Interrupt link GSIH configured for IRQ 23722second # [ 0.496479] iommu: DMA domain TLB invalidation policy: lazy mode723second # [ 0.497903] ACPI: bus type USB registered724test # [ 0.492565] iommu: Default domain type: Translated725second # [ 0.498749] usbcore: registered new interface driver usbfs726test # [ 0.493543] iommu: DMA domain TLB invalidation policy: lazy mode727second # [ 0.499710] usbcore: registered new interface driver hub728test # [ 0.494936] ACPI: bus type USB registered729second # [ 0.500702] usbcore: registered new device driver usb730test # [ 0.495799] usbcore: registered new interface driver usbfs731test # [ 0.496764] usbcore: registered new interface driver hub732second # [ 0.502357] NetLabel: Initializing733second # [ 0.502691] NetLabel: domain hash size = 128734test # [ 0.497762] usbcore: registered new device driver usb735second # [ 0.503609] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO736test # [ 0.499408] NetLabel: Initializing737second # [ 0.504646] NetLabel: unlabeled traffic allowed by default738test # [ 0.499747] NetLabel: domain hash size = 128739second # [ 0.505610] PCI: Using ACPI for IRQ routing740test # [ 0.500669] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO741test # [ 0.501694] NetLabel: unlabeled traffic allowed by default742test # [ 0.502662] PCI: Using ACPI for IRQ routing743second # [ 0.583003] pci 0000:00:01.0: vgaarb: setting as boot VGA device744second # [ 0.583682] pci 0000:00:01.0: vgaarb: bridge control possible745test # [ 0.579846] pci 0000:00:01.0: vgaarb: setting as boot VGA device746second # [ 0.583682] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none747test # [ 0.580739] pci 0000:00:01.0: vgaarb: bridge control possible748second # [ 0.583695] vgaarb: loaded749second # [ 0.584496] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0750test # [ 0.580739] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none751test # [ 0.580747] vgaarb: loaded752second # [ 0.585501] hpet0: 3 comparators, 64-bit 100.000000 MHz counter753test # [ 0.581556] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0754test # [ 0.582630] hpet0: 3 comparators, 64-bit 100.000000 MHz counter755second # [ 0.588765] clocksource: Switched to clocksource kvm-clock756test # [ 0.585818] clocksource: Switched to clocksource kvm-clock757second # [ 0.591814] VFS: Disk quotas dquot_6.6.0758second # [ 0.592876] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)759test # [ 0.588923] VFS: Disk quotas dquot_6.6.0760second # [ 0.594560] pnp: PnP ACPI init761second # [ 0.595495] ACPI: IRQ 4 override to edge(!), high(!)762test # [ 0.589826] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)763test # [ 0.591478] pnp: PnP ACPI init764second # [ 0.596692] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved765test # [ 0.592381] ACPI: IRQ 4 override to edge(!), high(!)766second # [ 0.598576] pnp: PnP ACPI: found 5 devices767test # [ 0.593559] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved768test # [ 0.595462] pnp: PnP ACPI: found 5 devices769second # [ 0.605830] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns770second # [ 0.607676] clocksource: Switched to clocksource acpi_pm771test # [ 0.602721] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns772second # [ 0.608901] NET: Registered PF_INET protocol family773test # [ 0.604564] clocksource: Switched to clocksource acpi_pm774second # [ 0.610135] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)775test # [ 0.605774] NET: Registered PF_INET protocol family776test # [ 0.607004] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)777second # [ 0.623980] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)778second # [ 0.625964] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)779test # [ 0.620682] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)780second # [ 0.627629] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)781test # [ 0.622520] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)782second # [ 0.629285] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)783test # [ 0.624166] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)784second # [ 0.630822] TCP: Hash tables configured (established 8192 bind 8192)785test # [ 0.625804] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)786second # [ 0.632197] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)787test # [ 0.627363] TCP: Hash tables configured (established 8192 bind 8192)788second # [ 0.633827] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)789test # [ 0.628733] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)790second # [ 0.635191] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)791test # [ 0.630298] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)792second # [ 0.636739] NET: Registered PF_UNIX/PF_LOCAL protocol family793test # [ 0.631683] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)794second # [ 0.637945] NET: Registered PF_XDP protocol family795test # [ 0.633182] NET: Registered PF_UNIX/PF_LOCAL protocol family796second # [ 0.639007] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]797test # [ 0.634390] NET: Registered PF_XDP protocol family798second # [ 0.640315] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]799test # [ 0.635449] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]800second # [ 0.641594] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]801test # [ 0.636719] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]802second # [ 0.642982] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]803test # [ 0.637988] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]804second # [ 0.644402] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]805test # [ 0.639426] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]806second # [ 0.645794] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]807test # [ 0.640817] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]808second # [ 0.647778] ACPI: \_SB_.GSIA: Enabled at IRQ 16809test # [ 0.642235] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]810test # [ 0.644260] ACPI: \_SB_.GSIA: Enabled at IRQ 16811second # [ 0.649769] ACPI: \_SB_.GSIB: Enabled at IRQ 17812test # [ 0.646290] ACPI: \_SB_.GSIB: Enabled at IRQ 17813second # [ 0.651674] ACPI: \_SB_.GSIC: Enabled at IRQ 18814test # [ 0.648228] ACPI: \_SB_.GSIC: Enabled at IRQ 18815second # [ 0.653576] ACPI: \_SB_.GSID: Enabled at IRQ 19816second # [ 0.655331] PCI: CLS 0 bytes, default 64817test # [ 0.650189] ACPI: \_SB_.GSID: Enabled at IRQ 19818test # [ 0.651944] PCI: CLS 0 bytes, default 64819second # [ 0.656414] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3c6d2fa06f4, max_idle_ns: 440795322535 ns820test # [ 0.653208] Trying to unpack rootfs image as initramfs...821second # [ 0.658696] Trying to unpack rootfs image as initramfs...822test # [ 0.659132] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3c6d2fa06f4, max_idle_ns: 440795322535 ns823second # [ 0.694910] Initialise system trusted keyrings824second # [ 0.698322] workingset: timestamp_bits=40 max_order=18 bucket_order=0825test # [ 0.693428] Initialise system trusted keyrings826test # [ 0.694616] workingset: timestamp_bits=40 max_order=18 bucket_order=0827second # [ 0.714673] Key type asymmetric registered828test # [ 0.711504] Key type asymmetric registered829second # [ 0.719280] Asymmetric key parser 'x509' registered830second # [ 0.720355] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)831test # [ 0.716130] Asymmetric key parser 'x509' registered832test # [ 0.717261] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)833second # [ 0.724389] io scheduler mq-deadline registered834second # [ 0.725370] io scheduler kyber registered835test # [ 0.722228] io scheduler mq-deadline registered836test # [ 0.723266] io scheduler kyber registered837second # [ 0.728699] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled838test # [ 0.726571] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled839second # [ 0.732291] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A840test # [ 0.730129] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A841second # [ 0.737928] Linux agpgart interface v0.103842second # [ 0.738892] ACPI: bus type drm_connector registered843test # [ 0.735380] Linux agpgart interface v0.103844test # [ 0.736349] ACPI: bus type drm_connector registered845second # [ 0.741600] usbcore: registered new interface driver usbserial_generic846second # [ 0.742947] usbserial: USB Serial support registered for generic847test # [ 0.739463] usbcore: registered new interface driver usbserial_generic848test # [ 0.740797] usbserial: USB Serial support registered for generic849second # [ 0.746271] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.850second # [ 0.746271] Please enable it if your BIOS has the CPPC option.851second # [ 0.749004] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled852test # [ 0.744125] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.853test # [ 0.744125] Please enable it if your BIOS has the CPPC option.854test # [ 0.746833] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled855second # [ 0.753371] drop_monitor: Initializing network drop monitor service856second # [ 0.754764] NET: Registered PF_INET6 protocol family857test # [ 0.751224] drop_monitor: Initializing network drop monitor service858test # [ 0.752638] NET: Registered PF_INET6 protocol family859second # [ 0.758659] Segment Routing with IPv6860second # [ 0.762282] In-situ OAM (IOAM) with IPv6861second # [ 0.763446] IPI shorthand broadcast: enabled862test # [ 0.759199] Segment Routing with IPv6863test # [ 0.760005] In-situ OAM (IOAM) with IPv6864test # [ 0.761196] IPI shorthand broadcast: enabled865second # [ 0.768810] sched_clock: Marking stable (591021210, 177237498)->(854376860, -86118152)866test # [ 0.768681] sched_clock: Marking stable (592021907, 176086219)->(857497097, -89388971)867second # [ 0.775430] registered taskstats version 1868second # [ 0.776500] Loading compiled-in X.509 certificates869test # [ 0.772178] registered taskstats version 1870test # [ 0.773254] Loading compiled-in X.509 certificates871second # [ 0.791278] Demotion targets for Node 0: null872second # [ 0.794307] Key type .fscrypt registered873second # [ 0.795144] Key type fscrypt-provisioning registered874test # [ 0.790171] Demotion targets for Node 0: null875test # [ 0.791252] Key type .fscrypt registered876second # [ 0.798299] ima: No TPM chip found, activating TPM-bypass!877second # [ 0.799462] ima: Allocated hash algorithm: sha1878test # [ 0.794113] Key type fscrypt-provisioning registered879test # [ 0.795258] ima: No TPM chip found, activating TPM-bypass!880second # [ 0.802296] ima: No architecture policies found881second # [ 0.803409] PM: Magic number: 14:689:244882test # [ 0.798121] ima: Allocated hash algorithm: sha1883test # [ 0.799138] ima: No architecture policies found884second # [ 0.804986] RAS: Correctable Errors collector initialized.885test # [ 0.802257] PM: Magic number: 14:689:244886test # [ 0.803850] RAS: Correctable Errors collector initialized.887second # [ 0.815081] clk: Disabling unused clocks888second # [ 0.815975] PM: genpd: Disabling unused power domains889test # [ 0.812949] clk: Disabling unused clocks890test # [ 0.815119] PM: genpd: Disabling unused power domains891second # [ 0.912672] Freeing initrd memory: 29608K892second # [ 0.915428] Freeing unused decrypted memory: 2028K893second # [ 0.917915] Freeing unused kernel image (initmem) memory: 3656K894second # [ 0.919211] Write protecting the kernel read-only data: 32768k895second # [ 0.920723] Freeing unused kernel image (text/rodata gap) memory: 304K896test # [ 0.917425] Freeing initrd memory: 29608K897second # [ 0.922502] Freeing unused kernel image (rodata/data gap) memory: 692K898test # [ 0.920208] Freeing unused decrypted memory: 2028K899test # [ 0.922685] Freeing unused kernel image (initmem) memory: 3656K900test # [ 0.924074] Write protecting the kernel read-only data: 32768k901test # [ 0.925651] Freeing unused kernel image (text/rodata gap) memory: 304K902test # [ 0.927441] Freeing unused kernel image (rodata/data gap) memory: 692K903second # [ 0.955516] x86/mm: Checked W+X mappings: passed, no W+X pages found.904second # [ 0.956862] Run /init as init process905second # [ 0.965120] systemd[1]: Inserted module 'autofs4'906test # [ 0.960587] x86/mm: Checked W+X mappings: passed, no W+X pages found.907test # [ 0.962014] Run /init as init process908test # [ 0.970547] systemd[1]: Inserted module 'autofs4'909second # [ 0.980051] fuse: init (API version 7.45)910second # [ 0.985827] ACPI: \_SB_.GSIG: Enabled at IRQ 22911second # [ 0.988398] ACPI: \_SB_.GSIH: Enabled at IRQ 23912test # [ 0.985843] fuse: init (API version 7.45)913second # [ 0.991544] ACPI: \_SB_.GSIE: Enabled at IRQ 20914second # [ 0.993875] ACPI: \_SB_.GSIF: Enabled at IRQ 21915test # [ 0.991765] ACPI: \_SB_.GSIG: Enabled at IRQ 22916second # [ 0.999186] virtiofs virtio5: discovered new tag: nix-store917test # [ 0.994427] ACPI: \_SB_.GSIH: Enabled at IRQ 23918second # [ 1.001292] virtiofs virtio5: virtio_fs_setup_dax: No cache capability919test # [ 0.997663] ACPI: \_SB_.GSIE: Enabled at IRQ 20920test # [ 1.000430] ACPI: \_SB_.GSIF: Enabled at IRQ 21921second # [ 1.008789] virtiofs virtio6: discovered new tag: shared922second # [ 1.010869] virtiofs virtio6: virtio_fs_setup_dax: No cache capability923test # [ 1.005994] virtiofs virtio5: discovered new tag: nix-store924test # [ 1.008131] virtiofs virtio5: virtio_fs_setup_dax: No cache capability925second # [ 1.014310] virtiofs virtio7: discovered new tag: xchg926second # [ 1.016281] virtiofs virtio7: virtio_fs_setup_dax: No cache capability927test # [ 1.018208] virtiofs virtio6: discovered new tag: shared928test # [ 1.020279] virtiofs virtio6: virtio_fs_setup_dax: No cache capability929test # [ 1.023753] virtiofs virtio7: discovered new tag: xchg930test # [ 1.025816] virtiofs virtio7: virtio_fs_setup_dax: No cache capability931second # [ 1.038629] systemd[1]: Successfully made /usr/ read-only.932test # [ 1.044440] systemd[1]: Successfully made /usr/ read-only.933second # [ 1.374545] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)934second # [ 1.380437] systemd[1]: Detected virtualization kvm.935second # [ 1.381493] systemd[1]: Detected architecture x86-64.936second # [ 1.382561] systemd[1]: Running in initrd.937second # [ 1.383686] systemd[1]: Initializing machine ID from random generator.938second # [ 1.385162] systemd[1]: Hostname set to <second>.939test # [ 1.380473] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)940test # [ 1.387257] systemd[1]: Detected virtualization kvm.941test # [ 1.388353] systemd[1]: Detected architecture x86-64.942test # [ 1.389476] systemd[1]: Running in initrd.943test # [ 1.390640] systemd[1]: Initializing machine ID from random generator.944test # [ 1.392101] systemd[1]: Hostname set to <test>.945second # [ 1.439383] systemd[1]: bpf-restrict-fs: LSM BPF program attached946test # [ 1.446130] systemd[1]: bpf-restrict-fs: LSM BPF program attached947second # [ 1.467710] systemd[1]: Queued start job for default target Initrd Default Target.948second # [ 1.472388] systemd[1]: Created slice Slice /system/modprobe.949second # [ 1.473789] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.950second # [ 1.475465] systemd[1]: Expecting device /dev/disk/by-label/nixos...951second # [ 1.476809] systemd[1]: Reached target Path Units.952second # [ 1.477904] systemd[1]: Reached target Slice Units.953second # [ 1.478994] systemd[1]: Reached target Swaps.954test # [ 1.473997] systemd[1]: Queued start job for default target Initrd Default Target.955second # [ 1.479993] systemd[1]: Reached target Timer Units.956second # [ 1.481162] systemd[1]: Listening on D-Bus System Message Bus Socket.957second # [ 1.482672] systemd[1]: Listening on Journal Socket (/dev/log).958test # [ 1.478242] systemd[1]: Created slice Slice /system/modprobe.959second # [ 1.484056] systemd[1]: Listening on Journal Sockets.960test # [ 1.479717] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.961test # [ 1.481491] systemd[1]: Expecting device /dev/disk/by-label/nixos...962test # [ 1.482969] systemd[1]: Reached target Path Units.963test # [ 1.484930] systemd[1]: Reached target Slice Units.964test # [ 1.486012] systemd[1]: Reached target Swaps.965test # [ 1.487001] systemd[1]: Reached target Timer Units.966test # [ 1.488198] systemd[1]: Listening on D-Bus System Message Bus Socket.967second # [ 1.485298] systemd[1]: Listening on udev Control Socket.968test # [ 1.489631] systemd[1]: Listening on Journal Socket (/dev/log).969second # [ 1.495850] systemd[1]: Listening on udev Kernel Socket.970test # [ 1.491018] systemd[1]: Listening on Journal Sockets.971second # [ 1.497023] systemd[1]: Reached target Socket Units.972second # [ 1.498833] systemd[1]: Starting Create List of Static Device Nodes...973test # [ 1.492252] systemd[1]: Listening on udev Control Socket.974second # [ 1.505793] systemd[1]: Starting Load Kernel Module configfs...975test # [ 1.501469] systemd[1]: Listening on udev Kernel Socket.976test # [ 1.502652] systemd[1]: Reached target Socket Units.977test # [ 1.504485] systemd[1]: Starting Create List of Static Device Nodes...978second # [ 1.515806] systemd[1]: Starting Journal Service...979test # [ 1.511573] systemd[1]: Starting Load Kernel Module configfs...980second # [ 1.519970] systemd[1]: Starting Load Kernel Modules...981second # [ 1.522847] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os982test # [ 1.521632] systemd[1]: Starting Journal Service...983second # [ 1.528393] systemd[1]: Starting Coldplug All udev Devices...984test # [ 1.525808] systemd[1]: Starting Load Kernel Modules...985test # [ 1.528744] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os986test # [ 1.534615] systemd[1]: Starting Coldplug All udev Devices...987second # [ 1.541373] systemd[1]: Finished Create List of Static Device Nodes.988second # [ 1.549855] systemd[1]: modprobe@configfs.service: Deactivated successfully.989second # [ 1.552500] systemd-journald[65]: Collecting audit messages is disabled.990test # [ 1.549171] systemd[1]: Finished Create List of Static Device Nodes.991second # [ 1.557293] systemd[1]: Finished Load Kernel Module configfs.992test # [ 1.554702] systemd[1]: modprobe@configfs.service: Deactivated successfully.993test # [ 1.559238] systemd-journald[65]: Collecting audit messages is disabled.994second # [ 1.564732] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config995test # [ 1.562548] systemd[1]: Finished Load Kernel Module configfs.996second # [ 1.569760] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.997second # [ 1.576297] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...998test # [ 1.570559] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config999second # [ 1.579264] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1000test # [ 1.576629] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1001test # [ 1.581760] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1002test # [ 1.588110] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1003second # [ 1.608857] systemd[1]: Finished Load Kernel Modules.1004second # [ 1.614362] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1005second # [ 1.622410] systemd[1]: Starting Apply Kernel Variables...1006test # [ 1.617115] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1007test # [ 1.622164] systemd[1]: Finished Load Kernel Modules.1008test # [ 1.627989] systemd[1]: Starting Apply Kernel Variables...1009second # [ 1.634788] systemd[1]: Starting Create Static Device Nodes in /dev...1010test # [ 1.641621] systemd[1]: Starting Create Static Device Nodes in /dev...1011second # [ 1.650683] systemd[1]: Finished Apply Kernel Variables.1012second # [ 1.660335] systemd[1]: Finished Create Static Device Nodes in /dev.1013test # [ 1.656209] systemd[1]: Finished Apply Kernel Variables.1014second # [ 1.665477] systemd[1]: Reached target Preparation for Local File Systems.1015second # [ 1.671314] systemd[1]: Reached target Local File Systems.1016test # [ 1.667798] systemd[1]: Finished Create Static Device Nodes in /dev.1017second # [ 1.677300] systemd[1]: Starting Rule-based Manager for Device Events and Files...1018test # [ 1.673321] systemd[1]: Reached target Preparation for Local File Systems.1019test # [ 1.677164] systemd[1]: Reached target Local File Systems.1020second # [ 1.688710] systemd[1]: Started Journal Service.1021test # [ 1.684151] systemd[1]: Starting Rule-based Manager for Device Events and Files...1022second # [ 1.510974] systemd-modules-load[66]: Inserted module 'dm_mod'1023second # [ 1.515413] systemd-modules-load[66]: Inserted module 'virtio_balloon'1024second # [ 1.516752] systemd-modules-load[66]: Inserted module 'virtio_gpu'1025test # [ 1.694251] systemd[1]: Started Journal Service.1026test # [ 1.516856] systemd-modules-load[66]: Inserted module 'dm_mod'1027test # [ 1.520260] systemd-modules-load[66]: Inserted module 'virtio_balloon'1028second # [ 1.525879] systemd[1]: Starting Create System Files and Directories...1029test # [ 1.522312] systemd-modules-load[66]: Inserted module 'virtio_gpu'1030test # [ 1.530078] systemd[1]: Starting Create System Files and Directories...1031second # [ 1.552580] systemd[1]: Finished Create System Files and Directories.1032second # [ 1.555223] systemd-udevd[73]: Using default interface naming scheme 'v261'.1033test # [ 1.556750] systemd[1]: Finished Create System Files and Directories.1034test # [ 1.562389] systemd-udevd[73]: Using default interface naming scheme 'v261'.1035second # [ 1.576930] systemd[1]: Started Rule-based Manager for Device Events and Files.1036test # [ 1.583904] systemd[1]: Started Rule-based Manager for Device Events and Files.1037second # [ 1.590771] systemd[1]: Finished Coldplug All udev Devices.1038second # [ 1.591850] systemd[1]: Reached target System Initialization.1039second # [ 1.592933] systemd[1]: Reached target Basic System.1040test # [ 1.591756] systemd[1]: Finished Coldplug All udev Devices.1041test # [ 1.594526] systemd[1]: Reached target System Initialization.1042test # [ 1.595680] systemd[1]: Reached target Basic System.1043test # [ 1.939813] virtio_blk virtio2: 1/0/0 default/read/poll queues1044test # [ 1.949101] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1045second # [ 1.960173] virtio_blk virtio2: 1/0/0 default/read/poll queues1046second # [ 1.971119] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121047second # [ 1.981099] serio: i8042 KBD port at 0x60,0x64 irq 11048second # [ 1.983533] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1049second # [ 1.988790] serio: i8042 AUX port at 0x60,0x64 irq 121050test # [ 1.985849] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121051second # [ 1.997813] ehci-pci 0000:00:1d.7: EHCI Host Controller1052second # [ 1.998759] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11053second # [ 2.000707] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001054test # [ 1.996601] serio: i8042 KBD port at 0x60,0x64 irq 11055second # [ 2.007255] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001056second # [ 2.009584] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181057test # [ 2.005816] ehci-pci 0000:00:1d.7: EHCI Host Controller1058second # [ 2.010893] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11059test # [ 2.006689] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11060test # [ 2.008581] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001061second # [ 2.014719] usb usb1: Product: EHCI Host Controller1062test # [ 2.010085] serio: i8042 AUX port at 0x60,0x64 irq 121063second # [ 2.016491] usb usb1: Manufacturer: Linux 6.18.55 ehci_hcd1064second # [ 2.018449] usb usb1: SerialNumber: 0000:00:1d.71065second # [ 2.020369] hub 1-0:1.0: USB hub found1066test # [ 2.016104] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001067second # [ 2.022003] hub 1-0:1.0: 6 ports detected1068test # [ 2.017890] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181069second # [ 2.024237] uhci_hcd 0000:00:1d.0: UHCI Host Controller1070test # [ 2.021086] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11071test # [ 2.023298] usb usb1: Product: EHCI Host Controller1072test # [ 2.025101] usb usb1: Manufacturer: Linux 6.18.55 ehci_hcd1073test # [ 2.025956] usb usb1: SerialNumber: 0000:00:1d.71074second # [ 2.031248] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21075second # [ 2.032463] uhci_hcd 0000:00:1d.0: detected 2 ports1076test # [ 2.030121] hub 1-0:1.0: USB hub found1077test # [ 2.030768] hub 1-0:1.0: 6 ports detected1078test # [ 2.034306] uhci_hcd 0000:00:1d.0: UHCI Host Controller1079test # [ 2.035174] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21080second # [ 2.045474] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801081test # [ 2.049204] uhci_hcd 0000:00:1d.0: detected 2 ports1082second # [ 2.054265] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181083second # [ 2.055549] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11084test # [ 2.055002] SCSI subsystem initialized1085test # [ 2.056906] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801086second # [ 1.889831] systemd[1]: Starting Virtual Console Setup...1087second # [ 1.892791] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line.1088test # [ 2.066882] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181089test # [ 1.892993] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line.1090second # [ 1.899681] (udev-worker)[85]: Network interface NamePolicy= disabled on kernel command line.1091test # [ 1.902078] systemd[1]: Starting Virtual Console Setup...1092test # [ 1.905513] (udev-worker)[85]: Network interface NamePolicy= disabled on kernel command line.1093second # [ 2.088423] usb usb2: Product: UHCI Host Controller1094test # [ 2.086989] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11095second # [ 1.919058] systemd-vconsole-setup[96]: Configuration of first virtual console was skipped, ignoring remaining ones.1096second # [ 2.100980] usb usb2: Manufacturer: Linux 6.18.55 uhci_hcd1097second # [ 2.102171] usb usb2: SerialNumber: 0000:00:1d.01098second # [ 2.108005] hub 2-0:1.0: USB hub found1099second # [ 1.934590] systemd[1]: Finished Virtual Console Setup.1100test # [ 1.929982] systemd-vconsole-setup[97]: Configuration of first virtual console was skipped, ignoring remaining ones.1101test # [ 1.933187] systemd[1]: Finished Virtual Console Setup.1102second # [ 2.115398] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01103second # [ 2.117322] hub 2-0:1.0: 2 ports detected1104test # [ 2.112379] usb usb2: Product: UHCI Host Controller1105second # [ 2.118052] SCSI subsystem initialized1106test # [ 2.118624] usb usb2: Manufacturer: Linux 6.18.55 uhci_hcd1107second # [ 2.125388] uhci_hcd 0000:00:1d.1: UHCI Host Controller1108test # [ 2.125817] usb usb2: SerialNumber: 0000:00:1d.01109test # [ 1.951163] systemd[1]: Found device /dev/disk/by-label/nixos.1110test # [ 1.952686] systemd[1]: Reached target Initrd Root Device.1111second # [ 1.958134] systemd[1]: Found device /dev/disk/by-label/nixos.1112second # [ 1.960082] systemd[1]: Reached target Initrd Root Device.1113test # [ 1.956074] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1114second # [ 2.138659] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31115test # [ 2.135457] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01116second # [ 1.964291] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1117second # [ 2.146360] uhci_hcd 0000:00:1d.1: detected 2 ports1118test # [ 2.143219] hub 2-0:1.0: USB hub found1119second # [ 2.152286] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01120test # [ 2.147374] hub 2-0:1.0: 2 ports detected1121second # [ 2.158185] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181122second # [ 2.159959] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11123test # [ 2.156164] uhci_hcd 0000:00:1d.1: UHCI Host Controller1124test # [ 2.157033] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31125second # [ 2.163270] usb usb3: Product: UHCI Host Controller1126second # [ 2.164103] usb usb3: Manufacturer: Linux 6.18.55 uhci_hcd1127test # [ 1.984620] systemd-fsck[103]: nixos: clean, 12/65536 files, 13019/262144 blocks1128second # [ 2.167263] usb usb3: SerialNumber: 0000:00:1d.11129test # [ 2.165588] uhci_hcd 0000:00:1d.1: detected 2 ports1130second # [ 2.171998] hub 3-0:1.0: USB hub found1131test # [ 2.168187] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01132second # [ 1.996174] systemd-fsck[104]: nixos: clean, 12/65536 files, 13019/262144 blocks1133second # [ 2.175266] hub 3-0:1.0: 2 ports detected1134test # [ 2.169772] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181135second # [ 2.002523] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1136test # [ 1.998535] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1137test # [ 2.178166] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11138second # [ 2.184290] uhci_hcd 0000:00:1d.2: UHCI Host Controller1139second # [ 2.185129] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41140test # [ 2.183152] usb usb3: Product: UHCI Host Controller1141test # [ 2.183979] usb usb3: Manufacturer: Linux 6.18.55 uhci_hcd1142test # [ 2.186138] usb usb3: SerialNumber: 0000:00:1d.11143test # [ 2.189607] hub 3-0:1.0: USB hub found1144second # [ 2.195066] uhci_hcd 0000:00:1d.2: detected 2 ports1145second # [ 2.196298] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01146test # [ 2.193246] hub 3-0:1.0: 2 ports detected1147second # [ 2.199484] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181148second # [ 2.200785] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11149test # [ 2.196732] uhci_hcd 0000:00:1d.2: UHCI Host Controller1150test # [ 2.198793] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1151second # [ 2.205111] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1152test # [ 2.200331] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41153second # [ 2.206474] usb usb4: Product: UHCI Host Controller1154test # [ 2.202157] uhci_hcd 0000:00:1d.2: detected 2 ports1155second # [ 2.207973] usb usb4: Manufacturer: Linux 6.18.55 uhci_hcd1156second # [ 2.209036] usb usb4: SerialNumber: 0000:00:1d.21157test # [ 2.203623] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1158second # [ 2.210504] hub 4-0:1.0: USB hub found1159test # [ 2.204911] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01160test # [ 2.205956] ahci 0000:00:1f.2: flags: 64bit ncq only1161second # [ 2.211733] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1162test # [ 2.207698] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181163test # [ 2.209025] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11164test # [ 2.210436] usb usb4: Product: UHCI Host Controller1165second # [ 2.216016] hub 4-0:1.0: 2 ports detected1166test # [ 2.212107] usb usb4: Manufacturer: Linux 6.18.55 uhci_hcd1167test # [ 2.212990] usb usb4: SerialNumber: 0000:00:1d.21168second # [ 2.221088] ahci 0000:00:1f.2: flags: 64bit ncq only1169test # [ 2.220149] scsi host0: ahci1170test # [ 2.221915] hub 4-0:1.0: USB hub found1171second # [ 2.228296] scsi host0: ahci1172test # [ 2.223752] scsi host1: ahci1173second # [ 2.229545] scsi host1: ahci1174second # [ 2.231098] scsi host2: ahci1175test # [ 2.225724] hub 4-0:1.0: 2 ports detected1176second # [ 2.233189] scsi host3: ahci1177test # [ 2.227939] scsi host2: ahci1178test # [ 2.229313] scsi host3: ahci1179second # [ 2.235995] scsi host4: ahci1180test # [ 2.232278] scsi host4: ahci1181second # [ 2.239250] scsi host5: ahci1182test # [ 2.235097] scsi host5: ahci1183second # [ 2.239862] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11184test # [ 2.235716] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11185second # [ 2.248459] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11186test # [ 2.246573] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11187second # [ 2.252070] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11188second # [ 2.253469] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11189second # [ 2.254831] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11190second # [ 2.256231] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11191test # [ 2.251899] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11192second # [ 2.258235] usb 1-1: new high-speed USB device number 2 using ehci-pci1193test # [ 2.254982] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11194test # [ 2.256382] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11195test # [ 2.257744] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11196test # [ 2.273143] usb 1-1: new high-speed USB device number 2 using ehci-pci1197second # [ 2.389146] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001198second # [ 2.390476] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101199second # [ 2.391937] usb 1-1: Product: QEMU USB Tablet1200second # [ 2.392815] usb 1-1: Manufacturer: QEMU1201second # [ 2.393609] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11202second # [ 2.408128] hid: raw HID events driver (C) Jiri Kosina1203test # [ 2.402982] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001204test # [ 2.404594] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101205test # [ 2.406300] usb 1-1: Product: QEMU USB Tablet1206test # [ 2.407160] usb 1-1: Manufacturer: QEMU1207test # [ 2.407801] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11208test # [ 2.421888] hid: raw HID events driver (C) Jiri Kosina1209second # [ 2.361925] systemd[1]: Mounting /sysroot...1210test # [ 2.368668] systemd[1]: Mounting /sysroot...1211second # [ 2.569795] ata6: SATA link down (SStatus 0 SControl 300)1212second # [ 2.570865] ata5: SATA link down (SStatus 0 SControl 300)1213second # [ 2.572083] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1214second # [ 2.573130] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001215test # [ 2.568430] ata1: SATA link down (SStatus 0 SControl 300)1216second # [ 2.574320] ata3.00: applying bridge limits1217test # [ 2.569722] ata5: SATA link down (SStatus 0 SControl 300)1218second # [ 2.575466] ata2: SATA link down (SStatus 0 SControl 300)1219test # [ 2.571221] ata6: SATA link down (SStatus 0 SControl 300)1220second # [ 2.576717] ata1: SATA link down (SStatus 0 SControl 300)1221test # [ 2.572498] ata4: SATA link down (SStatus 0 SControl 300)1222second # [ 2.578166] ata4: SATA link down (SStatus 0 SControl 300)1223second # [ 2.579513] ata3.00: configured for UDMA/1001224test # [ 2.573972] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1225test # [ 2.575289] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001226second # [ 2.580868] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51227test # [ 2.576549] ata3.00: applying bridge limits1228test # [ 2.577680] ata2: SATA link down (SStatus 0 SControl 300)1229test # [ 2.578857] ata3.00: configured for UDMA/1001230test # [ 2.580014] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51231test # [ 2.630160] usbcore: registered new interface driver usbhid1232test # [ 2.631171] usbhid: USB HID core driver1233second # [ 2.640310] usbcore: registered new interface driver usbhid1234second # [ 2.643630] usbhid: USB HID core driver1235second # [ 2.657472] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1236test # [ 2.652115] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input21237second # [ 2.659067] 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/input21238second # [ 2.661869] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01239test # [ 2.657354] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01240second # [ 2.668415] cdrom: Uniform CD-ROM driver Revision: 3.201241test # [ 2.663599] EXT4-fs (vda): mounted filesystem caffa482-4a41-4548-b030-662c2be8f08a r/w with ordered data mode. Quota mode: none.1242second # [ 2.669439] EXT4-fs (vda): mounted filesystem 0b7dc441-2b25-4d9c-baa1-09bbbfcdbd1e r/w with ordered data mode. Quota mode: none.1243test # [ 2.665570] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1244second # [ 2.496561] systemd[1]: Mounted /sysroot.1245test # [ 2.493630] systemd[1]: Mounted /sysroot.1246second # [ 2.498378] systemd[1]: Reached target Initrd Root File System.1247test # [ 2.495828] systemd[1]: Reached target Initrd Root File System.1248second # [ 2.503082] systemd[1]: Starting Mountpoints Configured in the Real Root...1249test # [ 2.499116] systemd[1]: Starting Mountpoints Configured in the Real Root...1250test # [ 2.681362] cdrom: Uniform CD-ROM driver Revision: 3.201251second # [ 2.517605] systemd-sysroot-fstab-check[136]: /sysroot should be mounted in the initrd, will request daemon-reload.1252test # [ 2.515225] systemd-sysroot-fstab-check[136]: /sysroot should be mounted in the initrd, will request daemon-reload.1253second # [ 2.522081] systemd[1]: Reload requested from client PID 136 ('systemd-sysroot') (unit initrd-parse-etc.service)...1254second # [ 2.524889] systemd[1]: Reloading...1255test # [ 2.521612] systemd[1]: Reload requested from client PID 136 ('systemd-sysroot') (unit initrd-parse-etc.service)...1256test # [ 2.523621] systemd[1]: Reloading...1257second # [ 2.584036] systemd[1]: Reloading finished in 62 ms.1258test # [ 2.588165] systemd[1]: Reloading finished in 67 ms.1259second # [ 2.593518] systemd-sysroot-fstab-check[136]: Requesting initrd-fs.target/start/replace...1260second # [ 2.597229] systemd-sysroot-fstab-check[136]: Requesting swap.target/start/replace...1261second # [ 2.602893] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1262test # [ 2.599341] systemd-sysroot-fstab-check[136]: Requesting initrd-fs.target/start/replace...1263second # [ 2.604902] systemd[1]: Finished Mountpoints Configured in the Real Root.1264second # [ 2.607197] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1265test # [ 2.604169] systemd-sysroot-fstab-check[136]: Requesting swap.target/start/replace...1266test # [ 2.607785] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1267test # [ 2.609554] systemd[1]: Finished Mountpoints Configured in the Real Root.1268test # [ 2.611171] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1269second # [ 3.362904] systemd[1]: Mounting /sysroot/nix/.ro-store...1270second # [ 3.366616] systemd[1]: Mounting /sysroot/nix/.rw-store...1271second # [ 3.368130] systemd[1]: Mounting /sysroot/run...1272second # [ 3.373769] systemd[1]: Mounting /sysroot/tmp/shared...1273test # [ 3.369650] systemd[1]: Mounting /sysroot/nix/.ro-store...1274second # [ 3.377320] systemd[1]: Mounting /sysroot/tmp/xchg...1275test # [ 3.373135] systemd[1]: Mounting /sysroot/nix/.rw-store...1276test # [ 3.374822] systemd[1]: Mounting /sysroot/run...1277test # [ 3.379682] systemd[1]: Mounting /sysroot/tmp/shared...1278test # [ 3.386133] systemd[1]: Mounting /sysroot/tmp/xchg...1279second # [ 3.391719] systemd[1]: Mounted /sysroot/nix/.rw-store.1280second # [ 3.397116] systemd[1]: Mounted /sysroot/nix/.ro-store.1281second # [ 3.398744] systemd[1]: Mounted /sysroot/run.1282second # [ 3.402939] systemd[1]: Mounted /sysroot/tmp/shared.1283second # [ 3.404874] systemd[1]: Mounted /sysroot/tmp/xchg.1284test # [ 3.400709] systemd[1]: Mounted /sysroot/nix/.ro-store.1285test # [ 3.402988] systemd[1]: Mounted /sysroot/nix/.rw-store.1286second # [ 3.407652] systemd[1]: Starting rw-sysroot-nix-store.service...1287test # [ 3.406978] systemd[1]: Mounted /sysroot/run.1288test # [ 3.409321] systemd[1]: Mounted /sysroot/tmp/shared.1289test # [ 3.411201] systemd[1]: Mounted /sysroot/tmp/xchg.1290second # [ 3.415819] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1291second # [ 3.417820] systemd[1]: Finished rw-sysroot-nix-store.service.1292test # [ 3.414366] systemd[1]: Starting rw-sysroot-nix-store.service...1293test # [ 3.422785] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1294test # [ 3.424659] systemd[1]: Finished rw-sysroot-nix-store.service.1295second # [ 4.363390] systemd[1]: Mounting /sysroot/nix/store...1296test # [ 4.369183] systemd[1]: Mounting /sysroot/nix/store...1297second # [ 4.380084] systemd[1]: Mounted /sysroot/nix/store.1298second # [ 4.380993] systemd[1]: Reached target Initrd File Systems.1299second # [ 4.382354] systemd[1]: Starting Find NixOS closure...1300second # [ 4.386412] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1301test # [ 4.386036] systemd[1]: Mounted /sysroot/nix/store.1302test # [ 4.387450] systemd[1]: Reached target Initrd File Systems.1303test # [ 4.389810] systemd[1]: Starting Find NixOS closure...1304test # [ 4.393137] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1305second # [ 4.399658] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1306second # [ 4.401593] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1307second # [ 4.408094] systemd[1]: Finished Find NixOS closure.1308second # [ 4.408993] systemd[1]: Reached target Initrd Default Target.1309second # [ 4.410424] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1310test # [ 4.408101] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1311test # [ 4.409954] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1312test # [ 4.415126] systemd[1]: Finished Find NixOS closure.1313test # [ 4.417158] systemd[1]: Reached target Initrd Default Target.1314second # [ 4.421948] systemd[1]: Stopped target Initrd Default Target.1315test # [ 4.419136] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1316second # [ 4.423608] systemd[1]: Stopped target Basic System.1317second # [ 4.424814] systemd[1]: Stopped target Initrd Root Device.1318second # [ 4.427181] systemd[1]: Stopped target Path Units.1319second # [ 4.428087] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1320second # [ 4.429492] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1321second # [ 4.430847] systemd[1]: Stopped target Slice Units.1322second # [ 4.431809] systemd[1]: Stopped target Socket Units.1323second # [ 4.432769] systemd[1]: Stopped target System Initialization.1324second # [ 4.434173] systemd[1]: Stopped target Swaps.1325test # [ 4.431434] systemd[1]: Stopped target Initrd Default Target.1326second # [ 4.435825] systemd[1]: Stopped target Timer Units.1327second # [ 4.436795] systemd[1]: dbus.socket: Deactivated successfully.1328test # [ 4.432875] systemd[1]: Stopped target Basic System.1329second # [ 4.437891] systemd[1]: Closed D-Bus System Message Bus Socket.1330test # [ 4.435155] systemd[1]: Stopped target Initrd Root Device.1331second # [ 4.439167] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1332test # [ 4.436192] systemd[1]: Stopped target Path Units.1333second # [ 4.441168] systemd[1]: Stopped Find NixOS closure.1334test # [ 4.437268] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1335second # [ 4.442192] systemd[1]: Starting rw-sysroot-nix-store.service...1336test # [ 4.438611] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1337second # [ 4.443317] systemd[1]: systemd-sysctl.service: Deactivated successfully.1338test # [ 4.439979] systemd[1]: Stopped target Slice Units.1339test # [ 4.440933] systemd[1]: Stopped target Socket Units.1340second # [ 4.445162] systemd[1]: Stopped Apply Kernel Variables.1341second # [ 4.446151] systemd[1]: systemd-modules-load.service: Deactivated successfully.1342test # [ 4.442334] systemd[1]: Stopped target System Initialization.1343second # [ 4.447430] systemd[1]: Stopped Load Kernel Modules.1344test # [ 4.444148] systemd[1]: Stopped target Swaps.1345test # [ 4.445034] systemd[1]: Stopped target Timer Units.1346second # [ 4.448891] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1347test # [ 4.446037] systemd[1]: dbus.socket: Deactivated successfully.1348second # [ 4.451147] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1349test # [ 4.447309] systemd[1]: Closed D-Bus System Message Bus Socket.1350second # [ 4.452504] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1351test # [ 4.448798] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1352second # [ 4.453885] systemd[1]: Stopped Create System Files and Directories.1353test # [ 4.451172] systemd[1]: Stopped Find NixOS closure.1354second # [ 4.455392] systemd[1]: Stopped target Local File Systems.1355test # [ 4.452116] systemd[1]: Starting rw-sysroot-nix-store.service...1356second # [ 4.457246] systemd[1]: Stopped target Preparation for Local File Systems.1357test # [ 4.453233] systemd[1]: systemd-sysctl.service: Deactivated successfully.1358test # [ 4.454528] systemd[1]: Stopped Apply Kernel Variables.1359second # [ 4.459424] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1360test # [ 4.455769] systemd[1]: systemd-modules-load.service: Deactivated successfully.1361second # [ 4.460763] systemd[1]: Stopped Coldplug All udev Devices.1362test # [ 4.458085] systemd[1]: Stopped Load Kernel Modules.1363second # [ 4.462913] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1364test # [ 4.459593] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1365second # [ 4.464257] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1366test # [ 4.460949] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1367second # [ 4.465795] systemd[1]: Stopped Virtual Console Setup.1368test # [ 4.462562] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1369test # [ 4.463854] systemd[1]: Stopped Create System Files and Directories.1370test # [ 4.466141] systemd[1]: Stopped target Local File Systems.1371test # [ 4.467176] systemd[1]: Stopped target Preparation for Local File Systems.1372test # [ 4.468633] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1373second # [ 4.473457] systemd[1]: initrd-cleanup.service: Deactivated successfully.1374test # [ 4.470554] systemd[1]: Stopped Coldplug All udev Devices.1375second # [ 4.476250] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1376test # [ 4.473301] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1377second # [ 4.478787] systemd[1]: systemd-udevd.service: Deactivated successfully.1378test # [ 4.474641] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1379test # [ 4.475904] systemd[1]: Stopped Virtual Console Setup.1380second # [ 4.480567] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1381second # [ 4.482394] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1382second # [ 4.484228] systemd[1]: Finished rw-sysroot-nix-store.service.1383test # [ 4.480099] systemd[1]: initrd-cleanup.service: Deactivated successfully.1384second # [ 4.487779] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1385test # [ 4.485265] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1386second # [ 4.489825] systemd[1]: Closed udev Control Socket.1387test # [ 4.486440] systemd[1]: systemd-udevd.service: Deactivated successfully.1388second # [ 4.490967] systemd[1]: Starting Cleanup udev Database...1389second # [ 4.492060] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1390second # [ 4.494198] systemd[1]: Stopped Create Static Device Nodes in /dev.1391test # [ 4.490087] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1392second # [ 4.495407] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1393test # [ 4.492431] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1394second # [ 4.496832] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1395second # [ 4.498196] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1396test # [ 4.494213] systemd[1]: Finished rw-sysroot-nix-store.service.1397second # [ 4.499759] systemd[1]: Stopped Create List of Static Device Nodes.1398test # [ 4.496849] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1399test # [ 4.498375] systemd[1]: Closed udev Control Socket.1400test # [ 4.500118] systemd[1]: Starting Cleanup udev Database...1401test # [ 4.501795] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1402test # [ 4.503140] systemd[1]: Stopped Create Static Device Nodes in /dev.1403test # [ 4.504246] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1404test # [ 4.506166] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1405test # [ 4.507407] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1406second # [ 4.512236] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1407test # [ 4.508599] systemd[1]: Stopped Create List of Static Device Nodes.1408second # [ 4.514222] systemd[1]: Finished Cleanup udev Database.1409second # [ 4.515673] systemd[1]: Reached target Switch Root.1410second # [ 4.517498] systemd[1]: Starting NixOS Activation...1411test # [ 4.520294] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1412test # [ 4.523072] systemd[1]: Finished Cleanup udev Database.1413test # [ 4.524056] systemd[1]: Reached target Switch Root.1414test # [ 4.525298] systemd[1]: Starting NixOS Activation...1415second # [ 4.587637] initrd-nixos-activation-start[188]: booting system configuration /nix/store/0l1qpij3z4s5isdrdf49vcs4j4lr9zf4-nixos-system-second-test1416test # [ 4.583994] initrd-nixos-activation-start[188]: booting system configuration /nix/store/waww6jdj07q1nj13pp56h32sb9rb3834-nixos-system-test-test1417second # [ 4.616657] initrd-nixos-activation-start[188]: running activation script...1418test # [ 4.613162] initrd-nixos-activation-start[188]: running activation script...1419second # [ 4.784503] initrd-nixos-activation-start[211]: setting up /etc...1420test # [ 4.786108] initrd-nixos-activation-start[211]: setting up /etc...1421second # [ 4.856098] initrd-nixos-activation-start[211]: setting up age secrets...1422test # [ 4.856925] initrd-nixos-activation-start[211]: setting up age secrets...1423second # [ 4.936279] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1424second # [ 4.938136] systemd[1]: Finished NixOS Activation.1425second # [ 4.939821] systemd[1]: Starting Switch Root...1426second # [ 4.950635] systemd[1]: Switching root.1427test # [ 4.957035] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1428test # [ 4.959177] systemd[1]: Finished NixOS Activation.1429test # [ 4.960879] systemd[1]: Starting Switch Root...1430test # [ 4.971577] systemd[1]: Switching root.1431second # [ 5.253534] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).1432test # [ 5.268767] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).1433test # [ 5.356743] NET: Registered PF_VSOCK protocol family1434second # [ 5.366477] NET: Registered PF_VSOCK protocol family1435second # [ 5.720186] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1436test # [ 5.714839] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1437second # [ 5.725504] systemd[1]: Detected virtualization kvm.1438test # [ 5.720192] systemd[1]: Detected virtualization kvm.1439test # [ 5.721202] systemd[1]: Detected architecture x86-64.1440second # [ 5.726526] systemd[1]: Detected architecture x86-64.1441test # [ 5.722265] systemd[1]: Detected first boot.1442second # [ 5.727608] systemd[1]: Detected first boot.1443test # [ 5.724667] systemd[1]: Initializing machine ID from random generator.1444second # [ 5.730000] systemd[1]: Initializing machine ID from random generator.1445second # [ 5.795340] systemd[1]: bpf-restrict-fs: LSM BPF program attached1446test # [ 5.791264] systemd[1]: bpf-restrict-fs: LSM BPF program attached1447test # [ 5.851774] systemd[1]: Applying preset policy.1448second # [ 5.857206] systemd[1]: Applying preset policy.1449test # [ 5.994352] systemd[1]: Populated /etc with preset unit settings.1450second # [ 6.008841] systemd[1]: Populated /etc with preset unit settings.1451test # [ 6.170409] systemd[1]: initrd-switch-root.service: Deactivated successfully.1452test # [ 6.172082] systemd[1]: Stopped initrd-switch-root.service.1453second # [ 6.179136] systemd[1]: initrd-switch-root.service: Deactivated successfully.1454test # [ 6.174512] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1455second # [ 6.180828] systemd[1]: Stopped initrd-switch-root.service.1456test # [ 6.176662] systemd[1]: Created slice Slice /system/getty.1457test # [ 6.178191] systemd[1]: Created slice User and Session Slice.1458second # [ 6.183421] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1459test # [ 6.179253] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1460second # [ 6.185780] systemd[1]: Created slice Slice /system/getty.1461test # [ 6.180626] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1462test # [ 6.181890] systemd[1]: Expecting device /dev/hvc0...1463second # [ 6.187243] systemd[1]: Created slice User and Session Slice.1464test # [ 6.182749] systemd[1]: Expecting device /dev/ttyS0...1465second # [ 6.188311] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1466test # [ 6.183671] systemd[1]: Reached target Local Encrypted Volumes.1467test # [ 6.184672] systemd[1]: Stopped target initrd-fs.target.1468second # [ 6.189697] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1469second # [ 6.190946] systemd[1]: Expecting device /dev/hvc0...1470second # [ 6.191815] systemd[1]: Expecting device /dev/ttyS0...1471second # [ 6.192735] systemd[1]: Reached target Local Encrypted Volumes.1472second # [ 6.193724] systemd[1]: Stopped target initrd-fs.target.1473test # [ 6.185598] systemd[1]: Stopped target initrd-root-fs.target.1474test # [ 6.194670] systemd[1]: Stopped target initrd-switch-root.target.1475test # [ 6.195708] systemd[1]: Reached target Virtual Machines and Containers.1476test # [ 6.196792] systemd[1]: Reached target Path Units.1477second # [ 6.194635] systemd[1]: Stopped target initrd-root-fs.target.1478test # [ 6.197643] systemd[1]: Reached target Remote File Systems.1479second # [ 6.203777] systemd[1]: Stopped target initrd-switch-root.target.1480test # [ 6.198604] systemd[1]: Reached target Slice Units.1481test # [ 6.199494] systemd[1]: Reached target Swaps.1482second # [ 6.204818] systemd[1]: Reached target Virtual Machines and Containers.1483second # [ 6.205913] systemd[1]: Reached target Path Units.1484second # [ 6.206786] systemd[1]: Reached target Remote File Systems.1485test # [ 6.201270] systemd[1]: Listening on Query the User Interactively for a Password.1486second # [ 6.207774] systemd[1]: Reached target Slice Units.1487second # [ 6.208668] systemd[1]: Reached target Swaps.1488test # [ 6.203628] systemd[1]: Listening on Process Core Dump Socket.1489second # [ 6.210442] systemd[1]: Listening on Query the User Interactively for a Password.1490test # [ 6.205407] systemd[1]: Listening on Credential Encryption/Decryption.1491test # [ 6.207289] systemd[1]: Listening on Factory Reset Management.1492second # [ 6.212782] systemd[1]: Listening on Process Core Dump Socket.1493test # [ 6.208383] systemd[1]: Listening on Hostname Service Socket.1494second # [ 6.214535] systemd[1]: Listening on Credential Encryption/Decryption.1495second # [ 6.216407] systemd[1]: Listening on Factory Reset Management.1496test # [ 6.211186] systemd[1]: Starting Journal Log Access Socket...1497second # [ 6.217491] systemd[1]: Listening on Hostname Service Socket.1498test # [ 6.212513] systemd[1]: Listening on Journal Audit Socket.1499second # [ 6.219989] systemd[1]: Starting Journal Log Access Socket...1500test # [ 6.214984] systemd[1]: Listening on Console Output Muting Service Socket.1501second # [ 6.221436] systemd[1]: Listening on Journal Audit Socket.1502test # [ 6.216244] systemd[1]: Listening on Network Management Metrics Varlink Socket.1503test # [ 6.217619] systemd[1]: Listening on Network Management Varlink Socket.1504second # [ 6.223813] systemd[1]: Listening on Console Output Muting Service Socket.1505test # [ 6.218841] systemd[1]: Listening on Network Management Netlink Socket.1506second # [ 6.225068] systemd[1]: Listening on Network Management Metrics Varlink Socket.1507test # [ 6.220132] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1508second # [ 6.226508] systemd[1]: Listening on Network Management Varlink Socket.1509test # [ 6.221395] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1510second # [ 6.227721] systemd[1]: Listening on Network Management Netlink Socket.1511test # [ 6.222896] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1512second # [ 6.228946] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1513second # [ 6.230264] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1514test # [ 6.226658] systemd[1]: Listening on Disk Repartitioning Service Socket.1515second # [ 6.231749] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1516test # [ 6.227866] systemd[1]: Listening on Resolve Monitor Varlink Socket.1517test # [ 6.229055] systemd[1]: Listening on Resolve Service Varlink Socket.1518test # [ 6.230196] systemd[1]: Listening on udev Control Socket.1519second # [ 6.235754] systemd[1]: Listening on Disk Repartitioning Service Socket.1520test # [ 6.231231] systemd[1]: Listening on udev Varlink Socket.1521second # [ 6.237070] systemd[1]: Listening on Resolve Monitor Varlink Socket.1522second # [ 6.238303] systemd[1]: Listening on Resolve Service Varlink Socket.1523test # [ 6.233785] systemd[1]: Mounting Huge Pages File System...1524second # [ 6.239446] systemd[1]: Listening on udev Control Socket.1525second # [ 6.240560] systemd[1]: Listening on udev Varlink Socket.1526test # [ 6.237104] systemd[1]: Mounting POSIX Message Queue File System...1527second # [ 6.242539] systemd[1]: Mounting Huge Pages File System...1528second # [ 6.245422] systemd[1]: Mounting POSIX Message Queue File System...1529test # [ 6.243052] systemd[1]: Mounting Kernel Debug File System...1530second # [ 6.251409] systemd[1]: Mounting Kernel Debug File System...1531test # [ 6.249766] systemd[1]: Mounting Kernel Trace File System...1532second # [ 6.259547] systemd[1]: Mounting Kernel Trace File System...1533test # [ 6.256352] systemd[1]: Starting Create List of Static Device Nodes...1534second # [ 6.265946] systemd[1]: Starting Create List of Static Device Nodes...1535test # [ 6.265584] systemd[1]: Starting Load Kernel Module configfs...1536second # [ 6.278473] systemd[1]: Starting Load Kernel Module configfs...1537test # [ 6.273304] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1538second # [ 6.283870] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1539test # [ 6.288460] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1540second # [ 6.295324] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1541second # [ 6.300139] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1542test # [ 6.297033] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1543second # [ 6.304632] systemd[1]: Mounting FUSE Control File System...1544test # [ 6.301581] systemd[1]: Mounting FUSE Control File System...1545second # [ 6.308259] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671546test # [ 6.303089] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671547second # [ 6.314696] systemd[1]: Starting Journal Service...1548test # [ 6.310034] systemd[1]: Starting Journal Service...1549second # [ 6.317306] systemd[1]: Starting Load Kernel Modules...1550test # [ 6.316008] systemd[1]: Starting Load Kernel Modules...1551second # [ 6.325701] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1552test # [ 6.324265] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1553second # [ 6.338515] systemd[1]: Starting Remount Root and Kernel File Systems...1554test # [ 6.333387] systemd[1]: Starting Remount Root and Kernel File Systems...1555second # [ 6.343225] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1556test # [ 6.338070] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1557second # [ 6.354757] systemd[1]: Starting Coldplug All udev Devices...1558test # [ 6.350319] systemd-journald[335]: Collecting audit messages is enabled.1559test # [ 6.352463] systemd[1]: Starting Coldplug All udev Devices...1560second # [ 6.365931] systemd[1]: Listening on Journal Log Access Socket.1561second # [ 6.369289] systemd-journald[314]: Collecting audit messages is enabled.1562test # [ 6.367571] systemd[1]: Listening on Journal Log Access Socket.1563second # [ 6.376170] loop: module loaded1564test # [ 6.371088] loop: module loaded1565second # [ 6.378384] systemd[1]: Mounted Huge Pages File System.1566test # [ 6.373785] systemd[1]: Mounted Huge Pages File System.1567test # [ 6.376286] systemd[1]: Mounted POSIX Message Queue File System.1568second # [ 6.385345] systemd[1]: Mounted POSIX Message Queue File System.1569test # [ 6.380497] systemd[1]: Mounted Kernel Debug File System.1570second # [ 6.388484] systemd[1]: Mounted Kernel Debug File System.1571test # [ 6.207329] systemd[1]: Queued start job for default target Multi-User System.1572test # [ 6.210543] systemd[1]: systemd-journald.service: Deactivated successfully.1573second # [ 6.392443] systemd[1]: Mounted Kernel Trace File System.1574test # [ 6.213536] systemd-modules-load[336]: Module 'ctr' is built in1575test # [ 6.391445] systemd[1]: Started Journal Service.1576second # [ 6.219875] systemd[1]: Queued start job for default target Multi-User System.1577second # [ 6.398519] systemd[1]: Started Journal Service.1578test # [ 6.216984] systemd-modules-load[336]: Inserted module 'loop'1579second # [ 6.224617] systemd[1]: systemd-journald.service: Deactivated successfully.1580second # [ 6.228553] systemd-modules-load[315]: Module 'ctr' is built in1581test # [ 6.225177] systemd[1]: Mounted Kernel Trace File System.1582second # [ 6.407522] EXT4-fs (vda): re-mounted 0b7dc441-2b25-4d9c-baa1-09bbbfcdbd1e.1583test # [ 6.403152] EXT4-fs (vda): re-mounted caffa482-4a41-4548-b030-662c2be8f08a.1584second # [ 6.233322] systemd-modules-load[315]: Inserted module 'loop'1585test # [ 6.230929] systemd[1]: Finished Create List of Static Device Nodes.1586test # [ 6.232204] systemd[1]: modprobe@configfs.service: Deactivated successfully.1587second # [ 6.237218] systemd[1]: Finished Create List of Static Device Nodes.1588test # [ 6.233438] systemd[1]: Finished Load Kernel Module configfs.1589second # [ 6.241176] systemd[1]: modprobe@configfs.service: Deactivated successfully.1590test # [ 6.238131] systemd[1]: Mounted FUSE Control File System.1591test # [ 6.240340] systemd[1]: Finished Load Kernel Modules.1592second # [ 6.245259] systemd[1]: Finished Load Kernel Module configfs.1593test # [ 6.244168] systemd[1]: Finished Remount Root and Kernel File Systems.1594second # [ 6.249206] systemd[1]: Finished Load Kernel Modules.1595second # [ 6.252852] systemd[1]: Finished Remount Root and Kernel File Systems.1596second # [ 6.255262] systemd[1]: Mounted FUSE Control File System.1597test # [ 6.254135] systemd[1]: Listening on Disk Image Download Service Socket.1598test # [ 6.256559] systemd[1]: Mounting Kernel Configuration File System...1599second # [ 6.264155] systemd[1]: Listening on Disk Image Download Service Socket.1600test # [ 6.261564] systemd[1]: Starting Firewall...1601test # [ 6.262604] systemd-oomd[338]: No swap; memory pressure usage will be degraded1602second # [ 6.266874] systemd-oomd[316]: No swap; memory pressure usage will be degraded1603second # [ 6.271856] systemd[1]: Mounting Kernel Configuration File System...1604second # [ 6.275104] systemd[1]: Starting Firewall...1605test # [ 6.271114] systemd[1]: Starting Flush Journal to Persistent Storage...1606test # [ 6.272406] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1607test # [ 6.279869] systemd[1]: Starting Load/Save OS Random Seed...1608second # [ 6.283990] systemd[1]: Starting Flush Journal to Persistent Storage...1609second # [ 6.285253] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1610second # [ 6.295070] systemd[1]: Starting Load/Save OS Random Seed...1611test # [ 6.293076] systemd[1]: Starting Apply Kernel Variables...1612second # [ 6.304107] systemd[1]: Starting Apply Kernel Variables...1613test # [ 6.301735] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1614test # [ 6.303105] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1615test # [ 6.304630] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1616second # [ 6.309567] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1617second # [ 6.311184] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1618second # [ 6.312922] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1619test # [ 6.517323] systemd-journald[335]: Received client request to flush runtime journal.1620second # [ 6.523828] systemd-journald[314]: Received client request to flush runtime journal.1621test # [ 6.523923] systemd[1]: Mounted Kernel Configuration File System.1622second # [ 6.532304] systemd[1]: Finished Load/Save OS Random Seed.1623test # [ 6.528642] systemd[1]: Finished Load/Save OS Random Seed.1624second # [ 6.536341] systemd[1]: Reached target First Boot Complete.1625test # [ 6.533615] systemd[1]: Reached target First Boot Complete.1626test # [ 6.534803] systemd[1]: Finished Apply Kernel Variables.1627second # [ 6.539326] systemd[1]: Mounted Kernel Configuration File System.1628test # [ 6.535924] systemd[1]: Starting Network Name Resolution...1629test # [ 6.537230] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1630second # [ 6.542397] systemd[1]: Finished Apply Kernel Variables.1631test # [ 6.539168] systemd[1]: Starting Create Static Device Nodes in /dev...1632second # [ 6.544479] systemd[1]: Starting Network Name Resolution...1633test # [ 6.541166] systemd[1]: Finished Flush Journal to Persistent Storage.1634second # [ 6.545602] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1635second # [ 6.548927] systemd[1]: Starting Create Static Device Nodes in /dev...1636second # [ 6.550223] systemd[1]: Finished Flush Journal to Persistent Storage.1637test # [ 6.557665] systemd-resolved[365]: Positive Trust Anchors:1638test # [ 6.559350] systemd-resolved[365]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1639second # [ 6.566037] systemd-resolved[344]: Positive Trust Anchors:1640test # [ 6.562244] systemd-resolved[365]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161641second # [ 6.569297] systemd-resolved[344]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1642second # [ 6.571205] systemd-resolved[344]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161643test # [ 6.564976] systemd-resolved[365]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test1644second # [ 6.572878] systemd-resolved[344]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test1645test # [ 6.590317] systemd[1]: Finished Create Static Device Nodes in /dev.1646test # [ 6.594262] systemd[1]: Reached target Preparation for Local File Systems.1647second # [ 6.599242] systemd[1]: Finished Create Static Device Nodes in /dev.1648second # [ 6.600552] systemd[1]: Reached target Preparation for Local File Systems.1649test # [ 6.597703] systemd[1]: Starting Rule-based Manager for Device Events and Files...1650second # [ 6.606954] systemd[1]: Starting Rule-based Manager for Device Events and Files...1651test # [ 6.611322] systemd[1]: Finished Coldplug All udev Devices.1652second # [ 6.622246] systemd[1]: Finished Coldplug All udev Devices.1653test # [ 6.639414] systemd-resolved[365]: Using system hostname 'test'.1654test # [ 6.642523] systemd[1]: Started Network Name Resolution.1655second # [ 6.648895] systemd-resolved[344]: Using system hostname 'second'.1656second # [ 6.651566] systemd[1]: Started Network Name Resolution.1657test # [ 6.657213] systemd-udevd[378]: Using default interface naming scheme 'v261'.1658second # [ 6.667531] systemd-udevd[358]: Using default interface naming scheme 'v261'.1659test # [ 6.715762] systemd[1]: Started Rule-based Manager for Device Events and Files.1660second # [ 6.723955] systemd[1]: Started Rule-based Manager for Device Events and Files.1661test # [ 6.839142] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1662second # [ 6.845102] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1663second # [ 6.916958] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1664test # [ 6.918972] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1665test # [ 6.931307] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1666second # [ 6.942791] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1667second # [ 6.956912] (udev-worker)[386]: Network interface NamePolicy= disabled on kernel command line.1668test # [ 6.957043] (udev-worker)[413]: Network interface NamePolicy= disabled on kernel command line.1669test # [ 6.961364] (udev-worker)[408]: Network interface NamePolicy= disabled on kernel command line.1670second # [ 6.966334] (udev-worker)[385]: Network interface NamePolicy= disabled on kernel command line.1671test # [ 6.999253] systemd[1]: Mounting /run/wrappers...1672second # [ 7.010613] systemd[1]: Mounting /run/wrappers...1673test # [ 7.036356] systemd[1]: Mounted /run/wrappers.1674test # [ 7.037346] systemd[1]: Reached target Local File Systems.1675second # [ 7.041940] systemd[1]: Mounted /run/wrappers.1676second # [ 7.042910] systemd[1]: Reached target Local File Systems.1677test # [ 7.040473] systemd[1]: Listening on Boot Loader Control Service Socket.1678second # [ 7.046337] systemd[1]: Listening on Boot Loader Control Service Socket.1679test # [ 7.045820] systemd[1]: Starting register-nix-paths.service...1680second # [ 7.051112] systemd[1]: Starting register-nix-paths.service...1681test # [ 7.049051] systemd[1]: Starting Create SUID/SGID Wrappers...1682test # [ 7.050234] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1683second # [ 7.055095] systemd[1]: Starting Create SUID/SGID Wrappers...1684second # [ 7.056175] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1685second # [ 7.060082] systemd[1]: Starting Save Transient machine-id to Disk...1686test # [ 7.057172] systemd[1]: Starting Save Transient machine-id to Disk...1687test # [ 7.067100] systemd[1]: Starting Create System Files and Directories...1688second # [ 7.072219] systemd[1]: Starting Create System Files and Directories...1689test # [ 7.175330] systemd[1]: Finished Create System Files and Directories.1690second # [ 7.185425] systemd[1]: Finished Create System Files and Directories.1691test # [ 7.190219] systemd[1]: Starting Rebuild Journal Catalog...1692second # [ 7.198404] systemd[1]: Starting Rebuild Journal Catalog...1693test # [ 7.194270] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1694second # [ 7.204498] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1695second # [ 7.264329] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1696test # [ 7.262507] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1697second # [ 7.275239] systemd[1]: Finished Save Transient machine-id to Disk.1698test # [ 7.271620] systemd[1]: Finished Save Transient machine-id to Disk.1699test # [ 7.292724] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1700second # [ 7.304090] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1701test # [ 7.308441] systemd[1]: Finished Rebuild Journal Catalog.1702test # [ 7.315874] systemd[1]: Starting Update is Completed...1703second # [ 7.323227] systemd[1]: Finished Rebuild Journal Catalog.1704second # [ 7.327274] systemd[1]: Starting Update is Completed...1705test # [ 7.363612] systemd[1]: Finished Update is Completed.1706second # [ 7.372387] systemd[1]: Finished Update is Completed.1707test # [ 7.580554] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31708test # [ 7.589292] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1709second # [ 7.599636] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31710second # [ 7.440119] systemd[1]: Finished Firewall.1711second # [ 7.444043] systemd[1]: Reached target Preparation for Network.1712second # [ 7.445202] systemd[1]: Listening on Network Management Resolve Hook Socket.1713second # [ 7.626925] mousedev: PS/2 mouse device common for all mice1714second # [ 7.452618] systemd[1]: Starting Network Management...1715second # [ 7.644512] ACPI: button: Power Button [PWRF]1716test # [ 7.479469] systemd[1]: Finished Firewall.1717test # [ 7.482600] systemd[1]: Reached target Preparation for Network.1718test # [ 7.483873] systemd[1]: Listening on Network Management Resolve Hook Socket.1719test # [ 7.492373] systemd[1]: Starting Network Management...1720second # [ 7.766272] rtc_cmos PNP0B00:00: RTC can wake from S41721test # [ 7.628163] mousedev: PS/2 mouse device common for all mice1722second # [ 7.791950] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1723test # [ 7.626631] systemd-resolved[365]: Clock change detected. Flushing caches.1724second # [ 7.649894] systemd-resolved[344]: Clock change detected. Flushing caches.1725test # [ 7.630663] ACPI: button: Power Button [PWRF]1726test # [ 7.761638] rtc_cmos PNP0B00:00: RTC can wake from S41727test # [ 7.778837] Console: switching to colour dummy device 80x251728test # [ 7.800206] rtc_cmos PNP0B00:00: registered as rtc01729test # [ 7.800304] rtc_cmos PNP0B00:00: setting system clock to 2026-10-05T20:13:18 UTC (1791231198)1730test # [ 7.800398] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1731second # [ 7.676480] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1732second # [ 7.683583] systemd[1]: Finished Create SUID/SGID Wrappers.1733second # [ 7.695094] systemd[1]: Finished register-nix-paths.service.1734test # [ 7.691338] systemd[1]: Finished register-nix-paths.service.1735second # [ 7.696546] systemd[1]: Reached target System Initialization.1736second # [ 7.699843] systemd[1]: Started Discard unused filesystem blocks once a week.1737second # [ 7.701306] systemd[1]: Started Daily Cleanup of Temporary Directories.1738second # [ 7.703170] systemd[1]: Reached target Timer Units.1739second # [ 7.705106] systemd[1]: Listening on D-Bus System Message Bus Socket.1740second # [ 7.706515] systemd[1]: Listening on Nix Daemon Socket.1741second # [ 7.711101] systemd[1]: Listening on Authorization Manager Agent Helper.1742second # [ 7.712466] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1743second # [ 7.714001] systemd[1]: Reached target Socket Units.1744second # [ 7.721346] systemd[1]: Starting D-Bus System Message Bus...1745test # [ 7.809589] parport_pc 00:02: reported by Plug and Play ACPI1746test # [ 7.809677] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1747test # [ 7.820435] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input41748test # [ 7.734452] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1749test # [ 7.737403] systemd[1]: Finished Create SUID/SGID Wrappers.1750test # [ 7.739314] systemd[1]: Reached target System Initialization.1751test # [ 7.742837] systemd[1]: Started Discard unused filesystem blocks once a week.1752test # [ 7.744273] systemd[1]: Started Daily Cleanup of Temporary Directories.1753test # [ 7.745988] systemd[1]: Reached target Timer Units.1754test # [ 7.747886] systemd[1]: Listening on D-Bus System Message Bus Socket.1755test # [ 7.749148] systemd[1]: Listening on Nix Daemon Socket.1756test # [ 7.753112] systemd[1]: Listening on Authorization Manager Agent Helper.1757test # [ 7.754324] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1758test # [ 7.755781] systemd[1]: Reached target Socket Units.1759second # [ 7.758815] systemd-networkd[518]: /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.1760test # [ 7.760095] systemd[1]: Starting D-Bus System Message Bus...1761second # [ 7.764501] systemd-networkd[518]: /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.1762second # [ 7.768743] systemd-networkd[518]: lo: Link UP1763second # [ 7.769718] systemd-networkd[518]: lo: Gained carrier1764second # [ 7.777074] systemd[1]: Started Network Management.1765second # [ 7.782097] systemd-networkd[518]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1766second # [ 7.784793] systemd-networkd[518]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1767second # [ 7.787848] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1768second # [ 7.791217] systemd-networkd[518]: eth1: Link UP1769test # [ 7.830377] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1770second # [ 7.793759] systemd-networkd[518]: eth1: Gained carrier1771test # [ 7.907111] cfg80211: Loading compiled-in X.509 certificates for regulatory database1772second # [ 7.796169] systemd-networkd[518]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1773second # [ 7.826311] rtc_cmos PNP0B00:00: registered as rtc01774second # [ 7.826408] rtc_cmos PNP0B00:00: setting system clock to 2026-10-05T20:13:18 UTC (1791231198)1775test # [ 7.798706] systemd-networkd[541]: /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.1776test # [ 7.802782] systemd-networkd[541]: /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.1777test # [ 7.806662] systemd-networkd[541]: lo: Link UP1778test # [ 7.807980] systemd-networkd[541]: lo: Gained carrier1779test # [ 7.813824] systemd[1]: Started Network Management.1780test # [ 7.819418] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1781test # [ 7.820737] systemd-networkd[541]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1782test # [ 7.824191] systemd-networkd[541]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1783second # [ 7.829817] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1784test # [ 7.827242] systemd-networkd[541]: eth1: Link UP1785second # [ 7.831930] dbus-broker-launch[544]: Looking up NSS user entry for 'systemd-timesync'...1786test # [ 7.831489] systemd-networkd[541]: eth1: Gained carrier1787second # [ 7.826495] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1788second # [ 7.863010] cfg80211: Loading compiled-in X.509 certificates for regulatory database1789second # [ 7.838650] dbus-broker-launch[544]: NSS returned no entry for 'systemd-timesync'1790test # [ 7.833486] systemd-networkd[541]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1791second # [ 7.839993] dbus-broker-launch[544]: Invalid user-name in /nix/store/3liy0y9brnsp4nnmhcack0lswq5a9zs0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1792test # [ 7.951976] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1793test # [ 7.952065] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1794second # [ 7.863773] parport_pc 00:02: reported by Plug and Play ACPI1795second # [ 7.863859] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1796second # [ 7.885958] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1797test # [ 7.869305] dbus-broker-launch[565]: Looking up NSS user entry for 'systemd-timesync'...1798second # [ 7.873159] dbus-broker-launch[544]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/vxlcffmhw7wsis0ib871ppnb88l437is-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1799second # [ 7.876907] dbus-broker-launch[544]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/vxlcffmhw7wsis0ib871ppnb88l437is-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1800test # [ 8.014559] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1801test # [ 8.014664] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1802test # [ 8.039102] NET: Registered PF_PACKET protocol family1803second # [ 7.881222] dbus-broker-launch[544]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/4vj8rc4mpy3mjsnp8aajf0gycr1hfmlh-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1804test # [ 7.879618] dbus-broker-launch[565]: NSS returned no entry for 'systemd-timesync'1805test # [ 7.880913] dbus-broker-launch[565]: Invalid user-name in /nix/store/3liy0y9brnsp4nnmhcack0lswq5a9zs0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1806second # [ 7.885603] dbus-broker-launch[544]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/d527hwwggm2wz18p4v8cr39amcpx3g4c-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1807test # [ 7.883440] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1808second # [ 7.889945] systemd[1]: Started D-Bus System Message Bus.1809second # [ 7.892303] systemd[1]: Reached target Basic System.1810second # [ 7.895855] systemd[1]: Starting Network Manager Script Dispatcher Service...1811second # [ 7.900619] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1812test # [ 8.065307] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61813test # [ 8.065625] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51814second # [ 7.907369] systemd[1]: Starting Network Manager...1815second # [ 7.892382] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input41816second # [ 7.910244] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1817test # [ 7.912125] dbus-broker-launch[565]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/vxlcffmhw7wsis0ib871ppnb88l437is-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1818second # [ 7.920075] systemd[1]: Started backdoor.service.1819test # [ 7.916120] dbus-broker-launch[565]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/vxlcffmhw7wsis0ib871ppnb88l437is-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1820second # [ 7.924337] dbus-broker-launch[544]: Ready1821test # [ 7.920876] dbus-broker-launch[565]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/4vj8rc4mpy3mjsnp8aajf0gycr1hfmlh-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1822test # [ 7.926121] dbus-broker-launch[565]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/d527hwwggm2wz18p4v8cr39amcpx3g4c-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1823test # [ 7.930657] systemd[1]: Started D-Bus System Message Bus.1824test # [ 7.932312] systemd[1]: Reached target Basic System.1825second # [ 7.936367] systemd[1]: Starting Import lastlog data into lastlog2 database...1826test # [ 8.111589] [drm] Found bochs VGA, ID 0xb0c5.1827test # [ 8.111592] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1828test # [ 7.938145] systemd[1]: Starting Network Manager Script Dispatcher Service...1829test # [ 7.945440] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1830test # [ 8.122700] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1831test # [ 8.123554] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01832second # [ 7.953032] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1833second # [ 7.910331] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1834second # [ 7.978424] Console: switching to colour dummy device 80x251835test # [ 7.954121] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1836second # [ 7.964396] systemd[1]: Starting Post-Boot Actions...1837test # [ 7.965385] dbus-broker-launch[565]: Ready1838test # [ 7.973151] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1839second # [ 7.986065] systemd[1]: Started Reset console on configuration changes.1840test # [ 7.984764] systemd[1]: Starting Network Manager...1841second # [ 7.996226] 5y3ssc77g9a48j0jag4pj6wrwmh8mnj4-wifi-secrets[555]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1842test # [ 8.000267] systemd[1]: Started backdoor.service.1843second # [ 7.990336] NET: Registered PF_PACKET protocol family1844second # [ 8.033386] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1845second # [ 8.033489] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1846test # [ 8.011353] systemd[1]: Starting Import lastlog data into lastlog2 database...1847second # connecting to host...1848second # [ 8.048080] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1849test # [ 8.044971] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1850second # [ 8.051503] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1851test # [ 8.048355] 5y3ssc77g9a48j0jag4pj6wrwmh8mnj4-wifi-secrets[578]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1852test # [ 8.051550] 2fzr21av002hkddm9grsnsbb2wa3c1bn-wifi-secrets[579]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1853test # [ 8.063240] systemd[1]: Starting Post-Boot Actions...1854second # [ 8.069262] systemd[1]: Finished Post-Boot Actions.1855test # [ 8.087225] bjn7ymirp1h8ai1aki9b9jfc6bwb1c5s-wifi-secrets[580]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1856second # [ 8.165578] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61857second # [ 8.170722] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51858second # [ 8.097861] nsncd[559]: Oct 05 20:13:18.946 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1859test # [ 8.095096] systemd[1]: Started Reset console on configuration changes.1860second # [ 8.101186] systemd[1]: Started Name Service Cache Daemon (nsncd).1861second # [ 8.104967] systemd[1]: Reached target Host and Network Name Lookups.1862second # [ 8.106241] systemd[1]: Reached target User and Group Name Lookups.1863second # [ 8.115498] systemd[1]: Starting User Login Management...1864test # [ 8.114047] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1865second # [ 8.133047] systemd[1]: Starting Virtual Console Setup...1866test # [ 8.133652] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1867test # connecting to host...1868second # [ 8.142637] systemd[1]: Finished Import lastlog data into lastlog2 database.1869second # [ 8.146283] systemd[1]: Started Network Manager Script Dispatcher Service.1870test # [ 8.142723] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1871test # [ 8.150042] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1872test # [ 8.151250] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1873test # [ 8.159434] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1874second # [ 8.352164] [drm] Found bochs VGA, ID 0xb0c5.1875second # [ 8.352166] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1876test: Guest shell says: b'Spawning backdoor root shell...\n'1877second # [ 8.360101] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1878second # [ 8.360883] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01879test # [ 8.192276] systemd[1]: Finished Post-Boot Actions.1880test: connected to guest root shell1881test: (connecting took 8.85 seconds)1882test: (finished: waiting for the VM to finish booting, in 8.85 seconds)1883second # [ 8.217450] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1884test # [ 8.231738] nsncd[587]: Oct 05 20:13:19.103 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1885test # [ 8.235891] systemd[1]: Started Name Service Cache Daemon (nsncd).1886second # [ 8.241045] systemd-logind[591]: Watching system buttons on /dev/input/event2 (Power Button)1887second # [ 8.242694] systemd-logind[591]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1888second # [ 8.244316] systemd[1]: Starting Hostname Service...1889second # [ 8.249260] systemd-logind[591]: New seat seat0.1890second # [ 8.250182] systemd[1]: Started User Login Management.1891test # [ 8.250385] systemd[1]: Finished Import lastlog data into lastlog2 database.1892second # [ 8.256196] systemd[1]: Starting linger-users.service...1893test # [ 8.253253] systemd[1]: Started Network Manager Script Dispatcher Service.1894test # [ 8.258314] systemd[1]: Reached target Host and Network Name Lookups.1895test # [ 8.259593] systemd[1]: Reached target User and Group Name Lookups.1896test # [ 8.269107] systemd[1]: Starting User Login Management...1897test # [ 8.272490] systemd[1]: Starting Virtual Console Setup...1898second # [ 8.294912] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1899second # [ 8.296431] systemd[1]: Stopped Virtual Console Setup.1900second # [ 8.306213] systemd[1]: Starting Virtual Console Setup...1901test # [ 8.302077] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1902test # [ 8.484947] Console: switching to colour frame buffer device 160x501903test # [ 8.311507] systemd[1]: Starting Hostname Service...1904second # [ 8.323758] systemd[1]: linger-users.service: Deactivated successfully.1905second # [ 8.326691] systemd[1]: Finished linger-users.service.1906test # [ 8.553195] ppdev: user-space parallel port driver1907test # [ 8.553850] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1908second # [ 8.535457] ppdev: user-space parallel port driver1909second # [ 8.566729] Console: switching to colour frame buffer device 160x501910test # [ 8.389505] systemd-logind[635]: Watching system buttons on /dev/input/event2 (Power Button)1911test # [ 8.392589] systemd-logind[635]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1912test # [ 8.394247] systemd-logind[635]: New seat seat0.1913test # [ 8.400111] systemd[1]: Started User Login Management.1914test # [ 8.406247] systemd[1]: Starting linger-users.service...1915test # [ 8.416112] systemd-logind[635]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1916second # [ 8.572289] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1917second # [ 8.584390] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1918test # [ 8.597841] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1919test # [ 8.433539] systemd[1]: Started Hostname Service.1920test # [ 8.445699] systemd[1]: linger-users.service: Deactivated successfully.1921test # [ 8.448410] systemd[1]: Finished linger-users.service.1922second # [ 8.644460] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1923test # [ 8.640304] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1924second # [ 8.370620] systemd[1]: Started Hostname Service.1925second # [ 8.470394] systemd-logind[591]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1926second # [ 8.471930] systemd-networkd[518]: eth0: Link UP1927second # [ 8.476266] systemd-networkd[518]: eth0: Gained carrier1928second # [ 8.477446] systemd[1]: Started Network Manager.1929second # [ 8.478569] systemd[1]: Reached target Network.1930second # [ 8.483249] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1931second # [ 8.485602] systemd[1]: Starting Permit User Sessions...1932test # [ 8.486766] systemd-networkd[541]: eth0: Link UP1933test # [ 8.487711] systemd-networkd[541]: eth0: Gained carrier1934test # [ 8.511525] systemd[1]: Started Network Manager.1935test # [ 8.514788] systemd[1]: Reached target Network.1936test # [ 8.520934] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1937test # [ 8.526269] systemd[1]: Starting Permit User Sessions...1938second # [ 8.535098] systemd[1]: Finished Permit User Sessions.1939test # [ 8.565525] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1940test # [ 8.572405] systemd[1]: Stopped Virtual Console Setup.1941test # [ 8.591846] systemd[1]: Starting Virtual Console Setup...1942test # [ 8.603491] systemd[1]: Finished Permit User Sessions.1943second # [ 8.612642] systemd[1]: Started Getty on tty1.1944second # [ 8.619901] systemd[1]: Reached target Login Prompts.1945second # [ 8.626726] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1946second # [ 8.631087] systemd[1]: Stopped Virtual Console Setup.1947second # [ 8.648845] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1948second # [ 8.652333] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1949second # [ 8.661945] systemd-resolved[344]: eth0: Bus client set default route setting: yes1950second # [ 8.663360] systemd-resolved[344]: eth0: Bus client set DNS server list to: 10.0.2.31951test # [ 8.667715] NetworkManager[581]: <warn> [1791231199.5403] keyfile: load: "/var/run/NetworkManager/system-connections/one.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1952second # [ 8.853737] kvm_amd: TSC scaling supported1953second # [ 8.854300] kvm_amd: Nested Virtualization enabled1954test # [ 8.671035] NetworkManager[581]: <warn> [1791231199.5428] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1955second # [ 8.854857] kvm_amd: Nested Paging enabled1956second # [ 8.860574] kvm_amd: LBR virtualization supported1957second # [ 8.861183] kvm_amd: Virtual GIF supported1958second # [ 8.861661] kvm_amd: Virtual NMI enabled1959test # [ 8.702190] systemd[1]: Started Getty on tty1.1960test # [ 8.703127] systemd[1]: Reached target Login Prompts.1961test # [ 8.707711] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1962second # [ 8.890913] EDAC MC: Ver: 3.0.01963test # [ 8.709543] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1964test # [ 8.914832] kvm_amd: TSC scaling supported1965test # [ 8.915359] kvm_amd: Nested Virtualization enabled1966test # [ 8.918763] kvm_amd: Nested Paging enabled1967test # [ 8.920969] kvm_amd: LBR virtualization supported1968test # [ 8.921523] kvm_amd: Virtual GIF supported1969test # [ 8.922359] kvm_amd: Virtual NMI enabled1970second # [ 8.752110] systemd[1]: Reached target Multi-User System.1971test # [ 8.749531] systemd[1]: Reached target Multi-User System.1972second # [ 8.756827] systemd[1]: Starting Virtual Console Setup...1973test # [ 8.763778] systemd-resolved[365]: eth0: Bus client set default route setting: yes1974test # [ 8.767491] systemd-resolved[365]: eth0: Bus client set DNS server list to: 10.0.2.31975test # [ 8.976695] EDAC MC: Ver: 3.0.01976second # [ 8.844286] systemd-vconsole-setup[647]: Configuration of first virtual console was skipped, ignoring remaining ones.1977second # [ 8.848085] systemd[1]: Finished Virtual Console Setup.1978second # [ 8.851564] systemd[1]: Startup finished in 782ms (kernel) + 4.372s (initrd) + 3.695s (userspace) = 8.851s.1979test # [ 8.858573] systemd-vconsole-setup[656]: Configuration of first virtual console was skipped, ignoring remaining ones.1980test # [ 8.862449] systemd[1]: Finished Virtual Console Setup.1981test # [ 8.867271] systemd[1]: Startup finished in 789ms (kernel) + 4.362s (initrd) + 3.714s (userspace) = 8.866s.1982second # [ 9.034130] systemd-networkd[518]: eth1: Gained IPv6LL1983test # [ 9.097534] systemd-networkd[541]: eth1: Gained IPv6LL1984test: (finished: waiting for unit NetworkManager.service, in 10.01 seconds)1985test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1986test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.01 seconds)1987cleanup1988kill QemuMachine (pid 48)1989second # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/lb41b0anx1f98y9y5s9mdv97gjgsq740-python3-3.14.7/bin/python3.14)1990second # [2026-10-05T20:13:20Z INFO virtiofsd] Client disconnected, shutting down1991second # [2026-10-05T20:13:20Z INFO virtiofsd] Client disconnected, shutting down1992second # [2026-10-05T20:13:20Z INFO virtiofsd] Client disconnected, shutting down1993kill QemuMachine (pid 47)1994test # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/lb41b0anx1f98y9y5s9mdv97gjgsq740-python3-3.14.7/bin/python3.14)1995test # [2026-10-05T20:13:20Z INFO virtiofsd] Client disconnected, shutting down1996test # [2026-10-05T20:13:20Z INFO virtiofsd] Client disconnected, shutting down1997test # [2026-10-05T20:13:20Z INFO virtiofsd] Client disconnected, shutting down1998(finished: cleanup, in 0.10 seconds)1999Traceback (most recent call last):2000 File "/nix/store/251wq12mgs0m1nyxdl0s7w7affvaw9ks-nixos-test-driver-1.1/bin/.nixos-test-driver-wrapped", line 9, in <module>2001 sys.exit(main())2002 ~~~~^^2003 File "/nix/store/251wq12mgs0m1nyxdl0s7w7affvaw9ks-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/__init__.py", line 194, in main2004 driver.run_tests()2005 ~~~~~~~~~~~~~~~~^^2006 File "/nix/store/251wq12mgs0m1nyxdl0s7w7affvaw9ks-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/driver.py", line 437, in run_tests2007 self.test_script()2008 ~~~~~~~~~~~~~~~~^^2009 File "/nix/store/251wq12mgs0m1nyxdl0s7w7affvaw9ks-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/driver.py", line 395, in test_script2010 exec(self.tests, symbols, None)2011 ~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^2012 File "<string>", line 4, in <module>2013AssertionError: Expected mock password in connection file:2014[connection]2015autoconnect=true2016id=2017type=wifi20182019[wifi]2020mode=infrastructure2021ssid=20222023[wifi-security]2024key-mgmt=wpa-psk2025psk=2026