vm-test-run-switch-test-basics
checks.x86_64-linux.switchTest-basics
· build #21
· raw
1tribuchet: building on jamie2Machine 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 script7machine: waiting for unit multi-user.target8machine: waiting for the VM to finish booting9machine: starting vm10machine # Disk image does not exist, creating the virtualisation disk image...11machine: QEMU running (pid 45)12machine # Formatting '/build/vm-state-machine/tmp.KreyEGuCIf', fmt=raw size=107374182413machine # mke2fs 1.47.4 (6-Mar-2025)14machine # Discarding device blocks: 0/262144 done15machine # Creating filesystem with 262144 4k blocks and 65536 inodes16machine # Filesystem UUID: 2c528624-ee25-476f-b886-1ea4e7195b9417machine # Superblock backups stored on blocks:18machine # 32768, 98304, 163840, 22937619machine # 20machine # Allocating group tables: 0/8 done21machine # Writing inode tables: 0/8 done22machine # Creating journal (8192 blocks): done23machine # Writing superblocks and filesystem accounting information: 0/8 done24machine # 25machine # Virtualisation disk image created.26machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)27machine # 28machine # 29machine # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0030machine # Press Ctrl-B to configure iPXE (PCI 00:02.0)...31machine # 32machine # 33machine # 34machine # 35machine # iPXE (http://ipxe.org) 00:08.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0036machine # Press Ctrl-B to configure iPXE (PCI 00:08.0)...37machine # 38machine # 39machine # Booting from ROM...40machine # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.48 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Aug 28 06:22:54 UTC 202641machine # [ 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/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/init regInfo=/nix/store/nxhdj6s1nhh2dpkar5bij8rj2dhicfv8-closure-info/registration console=ttyS0,115200n8 console=tty042machine # [ 0.000000] BIOS-provided physical RAM map:43machine # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable44machine # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved45machine # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved46machine # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable47machine # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved48machine # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved49machine # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved50machine # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved51machine # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved52machine # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved53machine # [ 0.000000] NX (Execute Disable) protection: active54machine # [ 0.000000] APIC: Static calls initialized55machine # [ 0.000000] SMBIOS 2.8 present.56machine # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/201457machine # [ 0.000000] DMI: Memory slots populated: 1/158machine # [ 0.000000] Hypervisor detected: KVM59machine # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000060machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d0061machine # [ 0.000000] kvm-clock: using sched offset of 458771338 cycles62machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns63machine # [ 0.000004] tsc: Detected 2400.010 MHz processor64machine # [ 0.000811] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000065machine # [ 0.000926] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs66machine # [ 0.000928] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT67machine # [ 0.002830] found SMP MP-table at [mem 0x000f5450-0x000f545f]68machine # [ 0.002842] Using GB pages for direct mapping69machine # [ 0.002955] RAMDISK: [mem 0x3e429000-0x3ffcffff]70machine # [ 0.002964] ACPI: Early table checksum verification disabled71machine # [ 0.002967] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )72machine # [ 0.002971] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)73machine # [ 0.002975] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)74machine # [ 0.002982] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)75machine # [ 0.002985] ACPI: FACS 0x000000003FFE0000 00004076machine # [ 0.002986] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)77machine # [ 0.002988] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)78machine # [ 0.002989] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)79machine # [ 0.002991] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)80machine # [ 0.002992] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]81machine # [ 0.002993] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]82machine # [ 0.002994] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]83machine # [ 0.002994] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]84machine # [ 0.002995] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]85machine # [ 0.002995] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]86machine # [ 0.002996] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]87machine # [ 0.003251] No NUMA configuration found88machine # [ 0.003251] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]89machine # [ 0.003254] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]90machine # [ 0.005400] Zone ranges:91machine # [ 0.005401] DMA [mem 0x0000000000001000-0x0000000000ffffff]92machine # [ 0.005402] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]93machine # [ 0.005404] Normal empty94machine # [ 0.005404] Device empty95machine # [ 0.005405] Movable zone start for each node96machine # [ 0.005406] Early memory node ranges97machine # [ 0.005406] node 0: [mem 0x0000000000001000-0x000000000009efff]98machine # [ 0.005407] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]99machine # [ 0.005408] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]100machine # [ 0.005425] On node 0, zone DMA: 1 pages in unavailable ranges101machine # [ 0.005674] On node 0, zone DMA: 97 pages in unavailable ranges102machine # [ 0.022353] On node 0, zone DMA32: 40 pages in unavailable ranges103machine # [ 0.022806] ACPI: PM-Timer IO Port: 0x608104machine # [ 0.022817] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])105machine # [ 0.022845] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23106machine # [ 0.022847] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)107machine # [ 0.022849] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)108machine # [ 0.022850] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)109machine # [ 0.022851] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)110machine # [ 0.022851] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)111machine # [ 0.022854] ACPI: Using ACPI (MADT) for SMP configuration information112machine # [ 0.022854] ACPI: HPET id: 0x8086a201 base: 0xfed00000113machine # [ 0.022858] TSC deadline timer available114machine # [ 0.022862] CPU topo: Max. logical packages: 1115machine # [ 0.022862] CPU topo: Max. logical dies: 1116machine # [ 0.022863] CPU topo: Max. dies per package: 1117machine # [ 0.022866] CPU topo: Max. threads per core: 1118machine # [ 0.022867] CPU topo: Num. cores per package: 1119machine # [ 0.022867] CPU topo: Num. threads per package: 1120machine # [ 0.022867] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs121machine # [ 0.022884] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()122machine # [ 0.022915] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]123machine # [ 0.022916] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]124machine # [ 0.022918] [mem 0x40000000-0xafffffff] available for PCI devices125machine # [ 0.022919] Booting paravirtualized kernel on KVM126machine # [ 0.022922] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns127machine # [ 0.027630] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1128machine # [ 0.029692] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152129machine # [ 0.029734] kvm-guest: PV spinlocks disabled, single CPU130machine # [ 0.029735] 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/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/init regInfo=/nix/store/nxhdj6s1nhh2dpkar5bij8rj2dhicfv8-closure-info/registration console=ttyS0,115200n8 console=tty0131machine # [ 0.029827] Unknown kernel command line parameters "regInfo=/nix/store/nxhdj6s1nhh2dpkar5bij8rj2dhicfv8-closure-info/registration", will be passed to user space.132machine # [ 0.029840] random: crng init done133machine # [ 0.029840] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes134machine # [ 0.029862] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)135machine # [ 0.029874] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)136machine # [ 0.029903] Fallback order for Node 0: 0137machine # [ 0.029906] Built 1 zonelists, mobility grouping on. Total pages: 262006138machine # [ 0.029907] Policy zone: DMA32139machine # [ 0.032218] mem auto-init: stack:all(zero), heap alloc:on, heap free:off140machine # [ 0.034499] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1141machine # [ 0.036749] allocated 2097152 bytes of page_ext142machine # [ 0.045913] ftrace: allocating 48733 entries in 192 pages143machine # [ 0.045915] ftrace: allocated 192 pages with 2 groups144machine # [ 0.046697] Dynamic Preempt: lazy145machine # [ 0.046847] rcu: Preemptible hierarchical RCU implementation.146machine # [ 0.046848] rcu: RCU event tracing is enabled.147machine # [ 0.046848] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.148machine # [ 0.046849] Trampoline variant of Tasks RCU enabled.149machine # [ 0.046850] Rude variant of Tasks RCU enabled.150machine # [ 0.046850] Tracing variant of Tasks RCU enabled.151machine # [ 0.046851] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.152machine # [ 0.046851] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1153machine # [ 0.046871] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.154machine # [ 0.046872] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.155machine # [ 0.046873] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.156machine # [ 0.051132] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16157machine # [ 0.051403] rcu: srcu_init: Setting srcu_struct sizes based on contention.158machine # [ 0.051410] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns159machine # [ 0.051507] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)160machine # [ 0.055033] Console: colour VGA+ 80x25161machine # [ 0.055036] printk: legacy console [tty0] enabled162machine # [ 0.084073] printk: legacy console [ttyS0] enabled163machine # [ 0.192131] ACPI: Core revision 20250807164machine # [ 0.193053] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns165machine # [ 0.194678] APIC: Switch to symmetric I/O mode setup166machine # [ 0.195692] x2apic enabled167machine # [ 0.196461] APIC: Switched APIC routing to: physical x2apic168machine # [ 0.198288] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1169machine # [ 0.199332] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns170machine # [ 0.201107] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400010)171machine # [ 0.202190] x86/cpu: User Mode Instruction Prevention (UMIP) activated172machine # [ 0.204265] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127173machine # [ 0.205105] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0174machine # [ 0.206108] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto175machine # [ 0.207105] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl176machine # [ 0.209105] Transient Scheduler Attacks: Vulnerable: No microcode177machine # [ 0.210104] Spectre V2 : Mitigation: Enhanced / Automatic IBRS178machine # [ 0.211104] Speculative Return Stack Overflow: Mitigation: Safe RET179machine # [ 0.212104] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization180machine # [ 0.214112] Spectre V2 : Enabling IBPB for BPF181machine # [ 0.215668] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier182machine # [ 0.216105] active return thunk: srso_alias_return_thunk183machine # [ 0.218024] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'184machine # [ 0.219104] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'185machine # [ 0.220104] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'186machine # [ 0.221104] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'187machine # [ 0.222104] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'188machine # [ 0.223104] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'189machine # [ 0.224104] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'190machine # [ 0.226105] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256191machine # [ 0.227104] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64192machine # [ 0.228104] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512193machine # [ 0.229104] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024194machine # [ 0.230104] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8195machine # [ 0.231104] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.196machine # [ 0.265326] Freeing SMP alternatives memory: 44K197machine # [ 0.266107] pid_max: default: 32768 minimum: 301198machine # [ 0.266983] LSM: initializing lsm=capability,landlock,yama,bpf,ima199machine # [ 0.268198] landlock: Up and running.200machine # [ 0.268851] Yama: becoming mindful.201machine # [ 0.269317] LSM support for eBPF active202machine # [ 0.270220] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)203machine # [ 0.271124] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)204machine # [ 0.274154] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)205machine # [ 0.275652] Performance Events: Fam17h+ core perfctr, AMD PMU driver.206machine # [ 0.276113] ... version: 2207machine # [ 0.276874] ... bit width: 48208machine # [ 0.277107] ... generic counters: 6209machine # [ 0.277866] ... generic bitmap: 000000000000003f210machine # [ 0.278107] ... fixed-purpose counters: 0211machine # [ 0.278867] ... fixed-purpose bitmap: 0000000000000000212machine # [ 0.279106] ... value mask: 0000ffffffffffff213machine # [ 0.280055] ... max period: 00007fffffffffff214machine # [ 0.280846] ... global_ctrl mask: 000000000000003f215machine # [ 0.281202] signal: max sigframe size: 3376216machine # [ 0.282018] rcu: Hierarchical SRCU implementation.217machine # [ 0.282754] rcu: Max phase no-delay instances is 400.218machine # [ 0.287721] smp: Bringing up secondary CPUs ...219machine # [ 0.288122] smp: Brought up 1 node, 1 CPU220machine # [ 0.288842] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)221machine # [ 0.289318] Memory: 941816K/1048024K available (17215K kernel code, 2726K rwdata, 13580K rodata, 3644K init, 2988K bss, 98840K reserved, 0K cma-reserved)222machine # [ 0.290328] devtmpfs: initialized223machine # [ 0.291134] x86/mm: Memory block size: 128MB224machine # [ 0.292853] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)225machine # [ 0.294075] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).226machine # [ 0.295190] pinctrl core: initialized pinctrl subsystem227machine # [ 0.296301] PM: RTC time: 02:36:19, date: 2026-09-04228machine # [ 0.299734] NET: Registered PF_NETLINK/PF_ROUTE protocol family229machine # [ 0.300474] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations230machine # [ 0.301128] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations231machine # [ 0.302246] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations232machine # [ 0.303117] audit: initializing netlink subsys (disabled)233machine # [ 0.304442] thermal_sys: Registered thermal governor 'fair_share'234machine # [ 0.304444] thermal_sys: Registered thermal governor 'bang_bang'235machine # [ 0.305108] thermal_sys: Registered thermal governor 'step_wise'236machine # [ 0.306110] audit: type=2000 audit(1788489380.094:1): state=initialized audit_enabled=0 res=1237machine # [ 0.308111] thermal_sys: Registered thermal governor 'user_space'238machine # [ 0.308113] thermal_sys: Registered thermal governor 'power_allocator'239machine # [ 0.309135] cpuidle: using governor menu240machine # [ 0.311385] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5241machine # [ 0.312396] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]242machine # [ 0.313110] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry243machine # [ 0.314118] PCI: Using configuration type 1 for base access244machine # [ 0.315274] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.245machine # [ 0.320374] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages246machine # [ 0.321108] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page247machine # [ 0.326108] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages248machine # [ 0.327107] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page249machine # [ 0.337530] ACPI: Added _OSI(Module Device)250machine # [ 0.338109] ACPI: Added _OSI(Processor Device)251machine # [ 0.338860] ACPI: Added _OSI(Processor Aggregator Device)252machine # [ 0.347653] ACPI: 1 ACPI AML tables successfully acquired and loaded253machine # [ 0.351534] ACPI: Interpreter enabled254machine # [ 0.352122] ACPI: PM: (supports S0 S3 S4 S5)255machine # [ 0.352863] ACPI: Using IOAPIC for interrupt routing256machine # [ 0.353171] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug257machine # [ 0.354107] PCI: Using E820 reservations for host bridge windows258machine # [ 0.357232] ACPI: Enabled 2 GPEs in block 00 to 3F259machine # [ 0.365881] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])260machine # [ 0.366114] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]261machine # [ 0.367574] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]262machine # [ 0.368230] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]263machine # [ 0.369461] PCI host bridge to bus 0000:00264machine # [ 0.370111] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]265machine # [ 0.371107] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]266machine # [ 0.372107] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]267machine # [ 0.373108] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]268machine # [ 0.374107] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]269machine # [ 0.375107] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]270machine # [ 0.376107] pci_bus 0000:00: root bus resource [bus 00-ff]271machine # [ 0.377135] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint272machine # [ 0.378550] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint273machine # [ 0.381205] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]274machine # [ 0.382119] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]275machine # [ 0.383129] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]276machine # [ 0.384402] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]277machine # [ 0.385875] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint278machine # [ 0.389115] pci 0000:00:02.0: BAR 0 [io 0xc140-0xc15f]279machine # [ 0.390000] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]280machine # [ 0.390877] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]281machine # [ 0.392113] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]282machine # [ 0.393667] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint283machine # [ 0.397127] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f]284machine # [ 0.398025] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]285machine # [ 0.398895] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]286machine # [ 0.400579] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint287machine # [ 0.403128] pci 0000:00:04.0: BAR 0 [io 0xc080-0xc0bf]288machine # [ 0.404005] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]289machine # [ 0.405129] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]290machine # [ 0.406661] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint291machine # [ 0.409018] pci 0000:00:05.0: BAR 0 [io 0xc180-0xc19f]292machine # [ 0.409808] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]293machine # [ 0.410129] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]294machine # [ 0.411928] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint295machine # [ 0.414945] pci 0000:00:06.0: BAR 0 [io 0xc1a0-0xc1bf]296machine # [ 0.415804] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]297machine # [ 0.416131] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]298machine # [ 0.417712] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint299machine # [ 0.420124] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f]300machine # [ 0.421114] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]301machine # [ 0.422091] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]302machine # [ 0.423564] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint303machine # [ 0.425802] pci 0000:00:08.0: BAR 0 [io 0xc1c0-0xc1df]304machine # [ 0.426114] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]305machine # [ 0.427117] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]306machine # [ 0.428113] pci 0000:00:08.0: ROM [mem 0xfeb80000-0xfebbffff pref]307machine # [ 0.430772] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint308machine # [ 0.432121] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]309machine # [ 0.433129] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]310machine # [ 0.434651] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint311machine # [ 0.436980] pci 0000:00:0a.0: BAR 0 [io 0xc0c0-0xc0ff]312machine # [ 0.438114] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]313machine # [ 0.439117] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]314machine # [ 0.440674] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint315machine # [ 0.442944] pci 0000:00:0b.0: BAR 0 [io 0xc1e0-0xc1ff]316machine # [ 0.443791] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]317machine # [ 0.444130] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]318machine # [ 0.445682] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint319machine # [ 0.447079] pci 0000:00:1d.0: BAR 4 [io 0xc200-0xc21f]320machine # [ 0.448176] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint321machine # [ 0.449826] pci 0000:00:1d.1: BAR 4 [io 0xc220-0xc23f]322machine # [ 0.450304] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint323machine # [ 0.452864] pci 0000:00:1d.2: BAR 4 [io 0xc240-0xc25f]324machine # [ 0.453619] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint325machine # [ 0.454788] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]326machine # [ 0.455329] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint327machine # [ 0.456410] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO328machine # [ 0.457352] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint329machine # [ 0.459154] pci 0000:00:1f.2: BAR 4 [io 0xc260-0xc27f]330machine # [ 0.460114] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]331machine # [ 0.461433] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint332machine # [ 0.462987] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]333machine # [ 0.466849] ACPI: PCI: Interrupt link LNKA configured for IRQ 10334machine # [ 0.467219] ACPI: PCI: Interrupt link LNKB configured for IRQ 10335machine # [ 0.468207] ACPI: PCI: Interrupt link LNKC configured for IRQ 11336machine # [ 0.469209] ACPI: PCI: Interrupt link LNKD configured for IRQ 11337machine # [ 0.470221] ACPI: PCI: Interrupt link LNKE configured for IRQ 10338machine # [ 0.471205] ACPI: PCI: Interrupt link LNKF configured for IRQ 10339machine # [ 0.472209] ACPI: PCI: Interrupt link LNKG configured for IRQ 11340machine # [ 0.473206] ACPI: PCI: Interrupt link LNKH configured for IRQ 11341machine # [ 0.474146] ACPI: PCI: Interrupt link GSIA configured for IRQ 16342machine # [ 0.475125] ACPI: PCI: Interrupt link GSIB configured for IRQ 17343machine # [ 0.476121] ACPI: PCI: Interrupt link GSIC configured for IRQ 18344machine # [ 0.477119] ACPI: PCI: Interrupt link GSID configured for IRQ 19345machine # [ 0.478123] ACPI: PCI: Interrupt link GSIE configured for IRQ 20346machine # [ 0.479123] ACPI: PCI: Interrupt link GSIF configured for IRQ 21347machine # [ 0.480121] ACPI: PCI: Interrupt link GSIG configured for IRQ 22348machine # [ 0.481123] ACPI: PCI: Interrupt link GSIH configured for IRQ 23349machine # [ 0.483133] iommu: Default domain type: Translated350machine # [ 0.483972] iommu: DMA domain TLB invalidation policy: lazy mode351machine # [ 0.485209] ACPI: bus type USB registered352machine # [ 0.485998] usbcore: registered new interface driver usbfs353machine # [ 0.486858] usbcore: registered new interface driver hub354machine # [ 0.487126] usbcore: registered new device driver usb355machine # [ 0.489045] NetLabel: Initializing356machine # [ 0.489583] NetLabel: domain hash size = 128357machine # [ 0.490107] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO358machine # [ 0.491098] NetLabel: unlabeled traffic allowed by default359machine # [ 0.491841] PCI: Using ACPI for IRQ routing360machine # [ 0.536492] pci 0000:00:01.0: vgaarb: setting as boot VGA device361machine # [ 0.537103] pci 0000:00:01.0: vgaarb: bridge control possible362machine # [ 0.537103] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none363machine # [ 0.537112] vgaarb: loaded364machine # [ 0.537848] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0365machine # [ 0.538109] hpet0: 3 comparators, 64-bit 100.000000 MHz counter366machine # [ 0.541201] clocksource: Switched to clocksource kvm-clock367machine # [ 0.545041] VFS: Disk quotas dquot_6.6.0368machine # [ 0.545767] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)369machine # [ 0.547183] pnp: PnP ACPI init370machine # [ 0.547991] ACPI: IRQ 4 override to edge(!), high(!)371machine # [ 0.548993] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved372machine # [ 0.550836] pnp: PnP ACPI: found 6 devices373machine # [ 0.558619] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns374machine # [ 0.560122] clocksource: Switched to clocksource acpi_pm375machine # [ 0.561123] NET: Registered PF_INET protocol family376machine # [ 0.562195] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)377machine # [ 0.579317] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)378machine # [ 0.580890] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)379machine # [ 0.582239] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)380machine # [ 0.583580] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)381machine # [ 0.584867] TCP: Hash tables configured (established 8192 bind 8192)382machine # [ 0.585994] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)383machine # [ 0.587330] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)384machine # [ 0.588473] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)385machine # [ 0.589780] NET: Registered PF_UNIX/PF_LOCAL protocol family386machine # [ 0.590781] NET: Registered PF_XDP protocol family387machine # [ 0.591646] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]388machine # [ 0.592719] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]389machine # [ 0.593767] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]390machine # [ 0.594907] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]391machine # [ 0.596038] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]392machine # [ 0.597164] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]393machine # [ 0.598940] ACPI: \_SB_.GSIA: Enabled at IRQ 16394machine # [ 0.601805] ACPI: \_SB_.GSIB: Enabled at IRQ 17395machine # [ 0.603750] ACPI: \_SB_.GSIC: Enabled at IRQ 18396machine # [ 0.605681] ACPI: \_SB_.GSID: Enabled at IRQ 19397machine # [ 0.607388] PCI: CLS 0 bytes, default 64398machine # [ 0.608272] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns399machine # [ 0.610054] Trying to unpack rootfs image as initramfs...400machine # [ 0.656129] Initialise system trusted keyrings401machine # [ 0.657158] workingset: timestamp_bits=40 max_order=18 bucket_order=0402machine # [ 0.680883] Key type asymmetric registered403machine # [ 0.681639] Asymmetric key parser 'x509' registered404machine # [ 0.682522] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)405machine # [ 0.685891] io scheduler mq-deadline registered406machine # [ 0.686717] io scheduler kyber registered407machine # [ 0.690217] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled408machine # [ 0.691570] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A409machine # [ 0.696010] Linux agpgart interface v0.103410machine # [ 0.696839] ACPI: bus type drm_connector registered411machine # [ 0.700127] usbcore: registered new interface driver usbserial_generic412machine # [ 0.701571] usbserial: USB Serial support registered for generic413machine # [ 0.704720] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.414machine # [ 0.704720] Please enable it if your BIOS has the CPPC option.415machine # [ 0.707050] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled416machine # [ 0.710846] drop_monitor: Initializing network drop monitor service417machine # [ 0.712078] NET: Registered PF_INET6 protocol family418machine # [ 0.715130] Segment Routing with IPv6419machine # [ 0.717753] In-situ OAM (IOAM) with IPv6420machine # [ 0.718926] IPI shorthand broadcast: enabled421machine # [ 0.727210] sched_clock: Marking stable (582015515, 144694186)->(803212964, -76503263)422machine # [ 0.732866] registered taskstats version 1423machine # [ 0.733907] Loading compiled-in X.509 certificates424machine # [ 0.751728] Demotion targets for Node 0: null425machine # [ 0.752629] Key type .fscrypt registered426machine # [ 0.755723] Key type fscrypt-provisioning registered427machine # [ 0.756768] ima: No TPM chip found, activating TPM-bypass!428machine # [ 0.757750] ima: Allocated hash algorithm: sha1429machine # [ 0.758583] ima: No architecture policies found430machine # [ 0.762730] PM: Magic number: 10:109:611431machine # [ 0.764414] RAS: Correctable Errors collector initialized.432machine # [ 0.772411] clk: Disabling unused clocks433machine # [ 0.776732] PM: genpd: Disabling unused power domains434machine # [ 0.931506] Freeing initrd memory: 28316K435machine # [ 0.934646] Freeing unused decrypted memory: 2028K436machine # [ 0.937219] Freeing unused kernel image (initmem) memory: 3644K437machine # [ 0.938365] Write protecting the kernel read-only data: 32768k438machine # [ 0.940249] Freeing unused kernel image (text/rodata gap) memory: 1216K439machine # [ 0.941815] Freeing unused kernel image (rodata/data gap) memory: 756K440machine # [ 0.992842] x86/mm: Checked W+X mappings: passed, no W+X pages found.441machine # [ 0.993970] Run /init as init process442machine # [ 1.003965] systemd[1]: Inserted module 'autofs4'443machine # [ 1.020650] fuse: init (API version 7.45)444machine # [ 1.026480] ACPI: \_SB_.GSIG: Enabled at IRQ 22445machine # [ 1.029144] ACPI: \_SB_.GSIH: Enabled at IRQ 23446machine # [ 1.032437] ACPI: \_SB_.GSIE: Enabled at IRQ 20447machine # [ 1.035117] ACPI: \_SB_.GSIF: Enabled at IRQ 21448machine # [ 1.062428] systemd[1]: Successfully made /usr/ read-only.449machine # [ 1.397984] systemd[1]: systemd 262-175b52e running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -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)450machine # [ 1.402846] systemd[1]: Detected virtualization kvm.451machine # [ 1.403678] systemd[1]: Detected architecture x86-64.452machine # [ 1.404548] systemd[1]: Running in initrd.453machine # [ 1.405648] systemd[1]: Initializing machine ID from random generator.454machine # [ 1.406898] systemd[1]: Hostname set to <machine>.455machine # [ 1.480734] systemd[1]: bpf-restrict-fs: LSM BPF program attached456machine # [ 1.518382] systemd[1]: Queued start job for default target Initrd Default Target.457machine # [ 1.523068] systemd[1]: Created slice Slice /system/modprobe.458machine # [ 1.524228] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.459machine # [ 1.525654] systemd[1]: Expecting device /dev/disk/by-label/nixos...460machine # [ 1.526763] systemd[1]: Reached target Path Units.461machine # [ 1.527719] systemd[1]: Reached target Slice Units.462machine # [ 1.528738] systemd[1]: Reached target Swaps.463machine # [ 1.529614] systemd[1]: Reached target Timer Units.464machine # [ 1.530617] systemd[1]: Listening on D-Bus System Message Bus Socket.465machine # [ 1.531898] systemd[1]: Listening on Journal Socket (/dev/log).466machine # [ 1.533040] systemd[1]: Listening on Journal Sockets.467machine # [ 1.534005] systemd[1]: Listening on udev Kernel Socket.468machine # [ 1.534904] systemd[1]: Reached target Socket Units.469machine # [ 1.536663] systemd[1]: Starting Create List of Static Device Nodes...470machine # [ 1.540506] systemd[1]: Starting Load Kernel Module 9pnet_virtio...471machine # [ 1.547259] systemd[1]: Starting Load Kernel Module configfs...472machine # [ 1.562940] systemd[1]: Starting Journal Service...473machine # [ 1.573090] systemd[1]: Starting Load Kernel Modules...474machine # [ 1.579791] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os475machine # [ 1.598112] netfs: FS-Cache loaded476machine # [ 1.602965] 9pnet: Installing 9P2000 support477machine # [ 1.614785] systemd[1]: Starting Coldplug All udev Devices...478machine # [ 1.627815] systemd[1]: Finished Create List of Static Device Nodes.479machine # [ 1.635426] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.480machine # [ 1.643120] systemd[1]: Finished Load Kernel Module 9pnet_virtio.481machine # [ 1.648180] systemd[1]: modprobe@configfs.service: Deactivated successfully.482machine # [ 1.650099] systemd-journald[67]: Collecting audit messages is disabled.483machine # [ 1.656085] systemd[1]: Finished Load Kernel Module configfs.484machine # [ 1.661503] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.485machine # [ 1.664041] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config486machine # [ 1.672776] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...487machine # [ 1.675847] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev488machine # [ 1.708273] systemd[1]: Finished Load Kernel Modules.489machine # [ 1.716908] systemd[1]: Starting Apply Kernel Variables...490machine # [ 1.725862] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.491machine # [ 1.737793] systemd[1]: Starting Create Static Device Nodes in /dev...492machine # [ 1.757277] systemd[1]: Finished Apply Kernel Variables.493machine # [ 1.770278] systemd[1]: Started Journal Service.494machine # [ 1.626569] systemd-modules-load[68]: Inserted module 'dm_mod'495machine # [ 1.630261] systemd-modules-load[68]: Inserted module 'virtio_balloon'496machine # [ 1.631352] systemd-modules-load[68]: Inserted module 'virtio_gpu'497machine # [ 1.636653] systemd[1]: Finished Create Static Device Nodes in /dev.498machine # [ 1.642077] systemd[1]: Reached target Preparation for Local File Systems.499machine # [ 1.645120] systemd[1]: Reached target Local File Systems.500machine # [ 1.647926] systemd[1]: Starting Create System Files and Directories...501machine # [ 1.655071] systemd[1]: Starting Rule-based Manager for Device Events and Files...502machine # [ 1.680369] systemd[1]: Finished Create System Files and Directories.503machine # [ 1.703312] systemd-udevd[83]: Using default interface naming scheme 'v261'.504machine # [ 1.726276] systemd[1]: Started Rule-based Manager for Device Events and Files.505machine # [ 1.768144] systemd[1]: Finished Coldplug All udev Devices.506machine # [ 1.770120] systemd[1]: Reached target System Initialization.507machine # [ 1.770962] systemd[1]: Reached target Basic System.508machine # [ 2.097397] virtio_blk virtio5: 1/0/0 default/read/poll queues509machine # [ 2.109807] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12510machine # [ 2.121518] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)511machine # [ 2.131090] serio: i8042 KBD port at 0x60,0x64 irq 1512machine # [ 2.131790] serio: i8042 AUX port at 0x60,0x64 irq 12513machine # [ 2.142375] ehci-pci 0000:00:1d.7: EHCI Host Controller514machine # [ 2.143136] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1515machine # [ 2.144874] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000516machine # [ 2.152810] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00517machine # [ 2.154748] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18518machine # [ 2.155879] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1519machine # [ 2.159728] usb usb1: Product: EHCI Host Controller520machine # [ 2.160552] usb usb1: Manufacturer: Linux 6.18.48 ehci_hcd521machine # [ 2.162491] usb usb1: SerialNumber: 0000:00:1d.7522machine # [ 2.163820] hub 1-0:1.0: USB hub found523machine # [ 2.165719] hub 1-0:1.0: 6 ports detected524machine # [ 2.167755] uhci_hcd 0000:00:1d.0: UHCI Host Controller525machine # [ 2.168469] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2526machine # [ 2.169504] uhci_hcd 0000:00:1d.0: detected 2 ports527machine # [ 2.184125] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c200528machine # [ 2.194454] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18529machine # [ 2.195679] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1530machine # [ 2.212716] usb usb2: Product: UHCI Host Controller531machine # [ 2.213401] usb usb2: Manufacturer: Linux 6.18.48 uhci_hcd532machine # [ 2.226727] usb usb2: SerialNumber: 0000:00:1d.0533machine # [ 2.083909] systemd[1]: Starting Virtual Console Setup...534machine # [ 2.092042] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line.535machine # [ 2.239640] hub 2-0:1.0: USB hub found536machine # [ 2.242409] hub 2-0:1.0: 2 ports detected537machine # [ 2.257883] uhci_hcd 0000:00:1d.1: UHCI Host Controller538machine # [ 2.258617] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3539machine # [ 2.265474] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0540machine # [ 2.128734] (udev-worker)[87]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.541machine # [ 2.131374] (udev-worker)[87]: Network interface NamePolicy= disabled on kernel command line.542machine # [ 2.277895] SCSI subsystem initialized543machine # [ 2.134565] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones.544machine # [ 2.138849] systemd[1]: Finished Virtual Console Setup.545machine # [ 2.296736] uhci_hcd 0000:00:1d.1: detected 2 ports546machine # [ 2.297520] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c220547machine # [ 2.307652] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18548machine # [ 2.168468] systemd[1]: Found device /dev/disk/by-label/nixos.549machine # [ 2.314070] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1550machine # [ 2.171144] systemd[1]: Reached target Initrd Root Device.551machine # [ 2.174068] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...552machine # [ 2.321714] usb usb3: Product: UHCI Host Controller553machine # [ 2.322433] usb usb3: Manufacturer: Linux 6.18.48 uhci_hcd554machine # [ 2.328732] usb usb3: SerialNumber: 0000:00:1d.1555machine # [ 2.333007] hub 3-0:1.0: USB hub found556machine # [ 2.338724] hub 3-0:1.0: 2 ports detected557machine # [ 2.342934] uhci_hcd 0000:00:1d.2: UHCI Host Controller558machine # [ 2.344741] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4559machine # [ 2.347805] uhci_hcd 0000:00:1d.2: detected 2 ports560machine # [ 2.348592] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c240561machine # [ 2.352797] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18562machine # [ 2.356378] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1563machine # [ 2.359757] usb usb4: Product: UHCI Host Controller564machine # [ 2.360470] usb usb4: Manufacturer: Linux 6.18.48 uhci_hcd565machine # [ 2.217834] systemd-fsck[106]: nixos: clean, 12/65536 files, 13019/262144 blocks566machine # [ 2.222922] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.567machine # [ 2.370756] usb usb4: SerialNumber: 0000:00:1d.2568machine # [ 2.374074] hub 4-0:1.0: USB hub found569machine # [ 2.375633] hub 4-0:1.0: 2 ports detected570machine # [ 2.398920] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode571machine # [ 2.400079] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)572machine # [ 2.406722] usb 1-1: new high-speed USB device number 2 using ehci-pci573machine # [ 2.410114] ahci 0000:00:1f.2: flags: 64bit ncq only574machine # [ 2.417819] scsi host0: ahci575machine # [ 2.420001] scsi host1: ahci576machine # [ 2.423354] scsi host2: ahci577machine # [ 2.425147] scsi host3: ahci578machine # [ 2.426927] scsi host4: ahci579machine # [ 2.428603] scsi host5: ahci580machine # [ 2.430069] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 44 lpm-pol 1581machine # [ 2.431592] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 44 lpm-pol 1582machine # [ 2.432874] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 44 lpm-pol 1583machine # [ 2.434058] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 44 lpm-pol 1584machine # [ 2.435191] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 44 lpm-pol 1585machine # [ 2.436334] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 44 lpm-pol 1586machine # [ 2.535927] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00587machine # [ 2.538811] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10588machine # [ 2.542008] usb 1-1: Product: QEMU USB Tablet589machine # [ 2.543789] usb 1-1: Manufacturer: QEMU590machine # [ 2.545335] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1591machine # [ 2.574934] hid: raw HID events driver (C) Jiri Kosina592machine # [ 2.479731] systemd[1]: Mounting /sysroot...593machine # [ 2.748353] ata5: SATA link down (SStatus 0 SControl 300)594machine # [ 2.750754] ata2: SATA link down (SStatus 0 SControl 300)595machine # [ 2.753333] ata6: SATA link down (SStatus 0 SControl 300)596machine # [ 2.755534] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)597machine # [ 2.758150] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100598machine # [ 2.760248] ata3.00: applying bridge limits599machine # [ 2.762201] ata1: SATA link down (SStatus 0 SControl 300)600machine # [ 2.764471] ata4: SATA link down (SStatus 0 SControl 300)601machine # [ 2.766972] ata3.00: configured for UDMA/100602machine # [ 2.769386] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5603machine # [ 2.836273] usbcore: registered new interface driver usbhid604machine # [ 2.840744] usbhid: USB HID core driver605machine # [ 2.866079] 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/input2606machine # [ 2.867911] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0607machine # [ 2.885772] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray608machine # [ 2.891129] EXT4-fs (vda): mounted filesystem 2c528624-ee25-476f-b886-1ea4e7195b94 r/w with ordered data mode. Quota mode: none.609machine # [ 2.897829] cdrom: Uniform CD-ROM driver Revision: 3.20610machine # [ 2.754623] systemd[1]: Mounted /sysroot.611machine # [ 2.755682] systemd[1]: Reached target Initrd Root File System.612machine # [ 2.757554] systemd[1]: Starting Mountpoints Configured in the Real Root...613machine # [ 2.773844] systemd-sysroot-fstab-check[141]: /sysroot should be mounted in the initrd, will request daemon-reload.614machine # [ 2.779065] systemd[1]: Reload requested from client PID 141 ('systemd-sysroot') (unit initrd-parse-etc.service)...615machine # [ 2.780589] systemd[1]: Reloading...616machine # [ 2.859547] systemd[1]: Reloading finished in 80 ms.617machine # [ 2.868288] systemd-sysroot-fstab-check[141]: Requesting initrd-fs.target/start/replace...618machine # [ 2.870803] systemd-sysroot-fstab-check[141]: Requesting swap.target/start/replace...619machine # [ 2.876845] systemd[1]: initrd-parse-etc.service: Deactivated successfully.620machine # [ 2.878304] systemd[1]: Finished Mountpoints Configured in the Real Root.621machine # [ 2.879351] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.622machine # [ 2.881678] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio623machine # [ 3.483258] systemd[1]: Mounting /sysroot/nix/.ro-store...624machine # [ 3.489078] systemd[1]: Mounting /sysroot/nix/.rw-store...625machine # [ 3.499172] systemd[1]: Mounting /sysroot/run...626machine # [ 3.509323] systemd[1]: Mounting /sysroot/tmp/shared...627machine # [ 3.522713] systemd[1]: Mounting /sysroot/tmp/xchg...628machine # [ 3.712215] 9p: Installing v9fs 9p2000 file system support629machine # [ 3.570616] systemd[1]: Mounted /sysroot/nix/.rw-store.630machine # [ 3.572841] systemd[1]: Mounted /sysroot/run.631machine # [ 3.579260] systemd[1]: Mounted /sysroot/nix/.ro-store.632machine # [ 3.580278] systemd[1]: Mounted /sysroot/tmp/shared.633machine # [ 3.581225] systemd[1]: Mounted /sysroot/tmp/xchg.634machine # [ 3.584764] systemd[1]: Starting rw-sysroot-nix-store.service...635machine # [ 3.596174] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.636machine # [ 3.598104] systemd[1]: Finished rw-sysroot-nix-store.service.637machine # [ 3.599856] systemd[1]: Mounting /sysroot/nix/store...638machine # [ 3.621111] systemd[1]: Mounted /sysroot/nix/store.639machine # [ 3.622244] systemd[1]: Reached target Initrd File Systems.640machine # [ 3.623853] systemd[1]: Starting Find NixOS closure...641machine # [ 3.626440] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...642machine # [ 3.646082] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.643machine # [ 3.653733] systemd[1]: Finished Find NixOS closure.644machine # [ 3.655279] systemd[1]: Reached target Initrd Default Target.645machine # [ 3.657292] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...646machine # [ 3.670625] systemd[1]: Stopped target Initrd Default Target.647machine # [ 3.671956] systemd[1]: Stopped target Basic System.648machine # [ 3.672981] systemd[1]: Stopped target Initrd Root Device.649machine # [ 3.675120] systemd[1]: Stopped target Path Units.650machine # [ 3.675832] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.651machine # [ 3.676854] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.652machine # [ 3.678273] systemd[1]: Stopped target Slice Units.653machine # [ 3.678991] systemd[1]: Stopped target Socket Units.654machine # [ 3.679721] systemd[1]: Stopped target System Initialization.655machine # [ 3.680500] systemd[1]: Stopped target Swaps.656machine # [ 3.681128] systemd[1]: Stopped target Timer Units.657machine # [ 3.682076] systemd[1]: dbus.socket: Deactivated successfully.658machine # [ 3.683108] systemd[1]: Closed D-Bus System Message Bus Socket.659machine # [ 3.684175] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.660machine # [ 3.685818] systemd[1]: Stopped Find NixOS closure.661machine # [ 3.686563] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio662machine # [ 3.688362] systemd[1]: Starting rw-sysroot-nix-store.service...663machine # [ 3.690181] systemd[1]: systemd-sysctl.service: Deactivated successfully.664machine # [ 3.691329] systemd[1]: Stopped Apply Kernel Variables.665machine # [ 3.692402] systemd[1]: systemd-modules-load.service: Deactivated successfully.666machine # [ 3.693622] systemd[1]: Stopped Load Kernel Modules.667machine # [ 3.694711] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.668machine # [ 3.697084] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.669machine # [ 3.698144] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.670machine # [ 3.699249] systemd[1]: Stopped Create System Files and Directories.671machine # [ 3.700438] systemd[1]: Stopped target Local File Systems.672machine # [ 3.701430] systemd[1]: Stopped target Preparation for Local File Systems.673machine # [ 3.702484] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.674machine # [ 3.704105] systemd[1]: Stopped Coldplug All udev Devices.675machine # [ 3.704878] systemd[1]: Stopping Rule-based Manager for Device Events and Files...676machine # [ 3.706055] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.677machine # [ 3.707765] systemd[1]: Stopped Virtual Console Setup.678machine # [ 3.718276] systemd[1]: initrd-cleanup.service: Deactivated successfully.679machine # [ 3.722071] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.680machine # [ 3.728244] systemd[1]: systemd-udevd.service: Deactivated successfully.681machine # [ 3.729353] systemd[1]: Stopped Rule-based Manager for Device Events and Files.682machine # [ 3.732524] systemd[1]: Starting Cleanup udev Database...683machine # [ 3.733389] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.684machine # [ 3.734572] systemd[1]: Stopped Create Static Device Nodes in /dev.685machine # [ 3.736221] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.686machine # [ 3.737374] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.687machine # [ 3.739200] systemd[1]: kmod-static-nodes.service: Deactivated successfully.688machine # [ 3.740162] systemd[1]: Stopped Create List of Static Device Nodes.689machine # [ 3.740995] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.690machine # [ 3.743143] systemd[1]: Finished rw-sysroot-nix-store.service.691machine # [ 3.759317] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.692machine # [ 3.760783] systemd[1]: Finished Cleanup udev Database.693machine # [ 3.762289] systemd[1]: Reached target Switch Root.694machine # [ 3.763862] systemd[1]: Starting NixOS Activation...695machine # [ 3.919637] initrd-nixos-activation-start[195]: booting system configuration /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test696machine # [ 3.979989] initrd-nixos-activation-start[195]: running activation script...697machine # [ 4.366874] initrd-nixos-activation-start[218]: setting up /etc...698machine # [ 4.610241] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.699machine # [ 4.612081] systemd[1]: Finished NixOS Activation.700machine # [ 4.613798] systemd[1]: Starting Switch Root...701machine # [ 4.627618] systemd[1]: Switching root.702machine # [ 4.901874] systemd-journald[67]: Received SIGTERM from PID 1 (systemd).703machine # [ 5.052845] NET: Registered PF_VSOCK protocol family704machine # [ 5.435025] systemd[1]: systemd 262-175b52e running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -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)705machine # [ 5.438820] systemd[1]: Detected virtualization kvm.706machine # [ 5.439515] systemd[1]: Detected architecture x86-64.707machine # [ 5.440316] systemd[1]: Detected first boot.708machine # [ 5.444273] systemd[1]: Initializing machine ID from random generator.709machine # [ 5.544519] systemd[1]: bpf-restrict-fs: LSM BPF program attached710machine # [ 5.651857] systemd[1]: Applying preset policy.711machine # [ 6.016477] systemd[1]: Populated /etc with preset unit settings.712machine # [ 6.398907] systemd[1]: initrd-switch-root.service: Deactivated successfully.713machine # [ 6.400419] systemd[1]: Stopped initrd-switch-root.service.714machine # [ 6.402758] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.715machine # [ 6.404948] systemd[1]: Created slice Slice /system/getty.716machine # [ 6.406235] systemd[1]: Created slice User and Session Slice.717machine # [ 6.407291] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.718machine # [ 6.408486] systemd[1]: Started Forward Password Requests to Wall Directory Watch.719machine # [ 6.409622] systemd[1]: Expecting device /dev/hvc0...720machine # [ 6.410345] systemd[1]: Expecting device /dev/ttyS0...721machine # [ 6.411095] systemd[1]: Reached target Local Encrypted Volumes.722machine # [ 6.411920] systemd[1]: Stopped target initrd-fs.target.723machine # [ 6.412762] systemd[1]: Stopped target initrd-root-fs.target.724machine # [ 6.413568] systemd[1]: Stopped target initrd-switch-root.target.725machine # [ 6.414412] systemd[1]: Reached target Virtual Machines and Containers.726machine # [ 6.415356] systemd[1]: Reached target Path Units.727machine # [ 6.416091] systemd[1]: Reached target Remote File Systems.728machine # [ 6.416860] systemd[1]: Reached target Slice Units.729machine # [ 6.417557] systemd[1]: Reached target Swaps.730machine # [ 6.419913] systemd[1]: Listening on Query the User Interactively for a Password.731machine # [ 6.434625] systemd[1]: Listening on Process Core Dump Socket.732machine # [ 6.436902] systemd[1]: Listening on Credential Encryption/Decryption.733machine # [ 6.439173] systemd[1]: Listening on Factory Reset Management.734machine # [ 6.440089] systemd[1]: Listening on Hostname Service Socket.735machine # [ 6.444990] systemd[1]: Starting Journal Log Access Socket...736machine # [ 6.446797] systemd[1]: Listening on Journal Audit Socket.737machine # [ 6.449157] systemd[1]: Listening on Console Output Muting Service Socket.738machine # [ 6.450627] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.739machine # [ 6.452252] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os740machine # [ 6.453600] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki741machine # [ 6.461031] systemd[1]: Listening on Disk Repartitioning Service Socket.742machine # [ 6.462068] systemd[1]: Listening on udev Varlink Socket.743machine # [ 6.465713] systemd[1]: Mounting Huge Pages File System...744machine # [ 6.468571] systemd[1]: Mounting POSIX Message Queue File System...745machine # [ 6.474770] systemd[1]: Mounting Kernel Debug File System...746machine # [ 6.480570] systemd[1]: Mounting Kernel Trace File System...747machine # [ 6.487460] systemd[1]: Starting Create List of Static Device Nodes...748machine # [ 6.489788] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio749machine # [ 6.498364] systemd[1]: Starting Load Kernel Module configfs...750machine # [ 6.500074] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm751machine # [ 6.505302] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore752machine # [ 6.509077] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse753machine # [ 6.519137] systemd[1]: Mounting FUSE Control File System...754machine # [ 6.521126] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67755machine # [ 6.544394] systemd[1]: Starting Journal Service...756machine # [ 6.572785] systemd[1]: Starting Load Kernel Modules...757machine # [ 6.590776] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...758machine # [ 6.605728] systemd[1]: Starting Remount Root and Kernel File Systems...759machine # [ 6.613171] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os760machine # [ 6.620457] systemd-journald[289]: Collecting audit messages is enabled.761machine # [ 6.632082] systemd[1]: Starting Coldplug All udev Devices...762machine # [ 6.503929] systemd[1]: Queued start job for default target Multi-User System.763machine # [ 6.507273] systemd[1]: systemd-journald.service: Deactivated successfully.764machine # [ 6.660467] systemd[1]: Started Journal Service.765machine # [ 6.523815] systemd[1]: Listening on Journal Log Access Socket.766machine # [ 6.525895] systemd[1]: Mounted Huge Pages File System.767machine # [ 6.673173] loop: module loaded768machine # [ 6.531689] systemd[1]: Mounted POSIX Message Queue File System.769machine # [ 6.532673] systemd[1]: Mounted Kernel Debug File System.770machine # [ 6.533469] systemd[1]: Mounted Kernel Trace File System.771machine # [ 6.537501] systemd[1]: Finished Create List of Static Device Nodes.772machine # [ 6.538532] systemd[1]: modprobe@configfs.service: Deactivated successfully.773machine # [ 6.539711] systemd-modules-load[290]: Inserted module 'loop'774machine # [ 6.545250] systemd[1]: Finished Load Kernel Module configfs.775machine # [ 6.546162] systemd[1]: Mounted FUSE Control File System.776machine # [ 6.552365] systemd[1]: Finished Load Kernel Modules.777machine # [ 6.701267] EXT4-fs (vda): re-mounted 2c528624-ee25-476f-b886-1ea4e7195b94.778machine # [ 6.564940] systemd[1]: Finished Remount Root and Kernel File Systems.779machine # [ 6.569951] systemd[1]: Listening on Disk Image Download Service Socket.780machine # [ 6.579093] systemd[1]: Mounting Kernel Configuration File System...781machine # [ 6.582185] systemd[1]: Starting Firewall...782machine # [ 6.597124] systemd[1]: Starting Flush Journal to Persistent Storage...783machine # [ 6.598179] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore784machine # [ 6.609658] systemd-oomd[292]: No swap; memory pressure usage will be degraded785machine # [ 6.612587] systemd[1]: Starting Load/Save OS Random Seed...786machine # [ 6.625201] systemd[1]: Starting Apply Kernel Variables...787machine # [ 6.644326] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...788machine # [ 6.645471] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os789machine # [ 6.646619] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.790machine # [ 6.825321] systemd-journald[289]: Received client request to flush runtime journal.791machine # [ 6.834518] systemd[1]: Mounted Kernel Configuration File System.792machine # [ 6.837800] systemd[1]: Finished Load/Save OS Random Seed.793machine # [ 6.840285] systemd[1]: Reached target First Boot Complete.794machine # [ 6.841717] systemd[1]: Finished Apply Kernel Variables.795machine # [ 6.842938] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.796machine # [ 6.845149] systemd[1]: Starting Create Static Device Nodes in /dev...797machine # [ 6.846534] systemd[1]: Finished Flush Journal to Persistent Storage.798machine # [ 6.893740] systemd[1]: Finished Create Static Device Nodes in /dev.799machine # [ 6.896456] systemd[1]: Reached target Preparation for Local File Systems.800machine # [ 6.900821] systemd[1]: Starting Rule-based Manager for Device Events and Files...801machine # [ 6.987586] systemd-udevd[330]: Using default interface naming scheme 'v261'.802machine # [ 6.996799] systemd[1]: Finished Coldplug All udev Devices.803machine # [ 7.095102] systemd[1]: Started Rule-based Manager for Device Events and Files.804machine # [ 7.236812] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse805machine # [ 7.260236] systemd[1]: Mounting /run/wrappers...806machine # [ 7.302642] systemd[1]: Mounted /run/wrappers.807machine # [ 7.304671] systemd[1]: Reached target Local File Systems.808machine # [ 7.310174] systemd[1]: Listening on Boot Loader Control Service Socket.809machine # [ 7.313897] systemd[1]: Starting register-nix-paths.service...810machine # [ 7.318346] systemd[1]: Starting Create SUID/SGID Wrappers...811machine # [ 7.319834] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.812machine # [ 7.332778] systemd[1]: Starting Save Transient machine-id to Disk...813machine # [ 7.345089] systemd[1]: Starting Create System Files and Directories...814machine # [ 7.377072] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.815machine # [ 7.451373] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.816machine # [ 7.456172] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.817machine # [ 7.467445] systemd[1]: Finished Save Transient machine-id to Disk.818machine # [ 7.525339] systemd[1]: Finished Create System Files and Directories.819machine # [ 7.536593] systemd[1]: Starting Rebuild Journal Catalog...820machine # [ 7.544278] systemd[1]: Starting Record System Boot/Shutdown in UTMP...821machine # [ 7.546476] (udev-worker)[363]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.822machine # [ 7.559097] (udev-worker)[363]: Network interface NamePolicy= disabled on kernel command line.823machine # [ 7.567416] (udev-worker)[362]: Network interface NamePolicy= disabled on kernel command line.824machine # [ 7.645593] systemd[1]: Finished Record System Boot/Shutdown in UTMP.825machine # [ 7.707517] systemd[1]: Finished Rebuild Journal Catalog.826machine # [ 7.716442] systemd[1]: Starting Update is Completed...827machine # [ 7.779776] systemd[1]: Finished Update is Completed.828machine # [ 7.828268] systemd[1]: Condition check resulted in Virtio network device being skipped.829machine # [ 7.831491] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore830machine # [ 7.833727] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.831machine # [ 7.837195] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67832machine # [ 7.839935] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore833machine # [ 7.841996] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os834machine # [ 7.845951] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os835machine # [ 8.021368] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.836machine # [ 8.024623] systemd[1]: Finished Create SUID/SGID Wrappers.837machine # [ 8.224635] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console838machine # [ 8.234417] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3839machine # [ 8.105248] systemd[1]: Finished Firewall.840machine # [ 8.272726] ACPI: button: Power Button [PWRF]841machine # [ 8.301773] mousedev: PS/2 mouse device common for all mice842machine # [ 8.354966] Console: switching to colour dummy device 80x25843machine # [ 8.368851] parport_pc 00:02: reported by Plug and Play ACPI844machine # [ 8.368973] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]845machine # [ 8.377377] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:09.0/virtio7/input/input4846machine # [ 8.378454] rtc_cmos 00:04: RTC can wake from S4847machine # [ 8.381633] rtc_cmos 00:04: registered as rtc0848machine # [ 8.386183] rtc_cmos 00:04: setting system clock to 2026-09-04T02:36:27 UTC (1788489387)849machine # [ 8.388560] systemd-journald[289]: Time jumped backwards, rotating.850machine # [ 8.371124] systemd[1]: Finished register-nix-paths.service.851machine # [ 8.373823] systemd[1]: Reached target System Initialization.852machine # [ 8.375566] systemd[1]: Started Discard unused filesystem blocks once a week.853machine # [ 8.377172] systemd[1]: Started Daily Cleanup of Temporary Directories.854machine # [ 8.389364] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs855machine # [ 8.432164] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized856machine # [ 8.380408] systemd[1]: Reached target Timer Units.857machine # [ 8.382104] systemd[1]: Listening on D-Bus System Message Bus Socket.858machine # [ 8.383394] systemd[1]: Listening on Nix Daemon Socket.859machine # [ 8.384394] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.860machine # [ 8.385920] systemd[1]: Reached target Socket Units.861machine # [ 8.387928] systemd[1]: Reached target Basic System.862machine # [ 8.391170] systemd[1]: Started backdoor.service.863machine # [ 8.398755] systemd[1]: Starting Import lastlog data into lastlog2 database...864machine # [ 8.411589] systemd[1]: Starting Name Service Cache Daemon (nsncd)...865machine # [ 8.560323] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt866machine # [ 8.560450] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD867machine # [ 8.428132] systemd[1]: Starting Post-Boot Actions...868machine # [ 8.439347] systemd[1]: Started Reset console on configuration changes.869machine # [ 8.462375] systemd[1]: Starting resolvconf update...870machine # [ 8.643473] [drm] Found bochs VGA, ID 0xb0c5.871machine # [ 8.515276] nsncd[506]: Sep 04 02:36:27.770 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"872machine # [ 8.521757] systemd[1]: Starting D-Bus System Message Bus...873machine # [ 8.529187] systemd[1]: Started Name Service Cache Daemon (nsncd).874machine # connecting to host...875machine # [ 8.543683] systemd[1]: Finished Post-Boot Actions.876machine: Guest shell says: b'Spawning backdoor root shell...\n'877machine # [ 8.566935] systemd[1]: Reached target Host and Network Name Lookups.878machine: connected to guest root shell879machine # [ 8.570218] systemd[1]: Reached target User and Group Name Lookups.880machine: (connecting took 9.24 seconds)881machine: (finished: waiting for the VM to finish booting, in 9.51 seconds)882machine # [ 8.729361] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.883machine # [ 8.610109] systemd[1]: Starting User Login Management...884machine # [ 8.615859] systemd[1]: Finished Import lastlog data into lastlog2 database.885machine # [ 8.762802] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic886machine # [ 8.768852] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6887machine # [ 8.772065] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5888machine # [ 8.800032] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0889machine # [ 8.735795] dbus-broker-launch[516]: Looking up NSS user entry for 'systemd-timesync'...890machine # [ 8.774529] dbus-broker-launch[516]: NSS returned no entry for 'systemd-timesync'891machine # [ 8.781557] dbus-broker-launch[516]: Invalid user-name in /nix/store/nxbpk401an3y90294yk3vj386qpkvmng-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"892machine # [ 8.785860] systemd[1]: Stopped target Host and Network Name Lookups.893machine # [ 8.787231] systemd[1]: Stopping Host and Network Name Lookups...894machine # [ 8.788339] systemd[1]: Stopped target User and Group Name Lookups.895machine # [ 8.789492] systemd[1]: Stopping User and Group Name Lookups...896machine # [ 8.792457] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...897machine # [ 9.137948] Console: switching to colour frame buffer device 160x50898machine # [ 9.165991] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device899machine # [ 8.800246] systemd[1]: nscd.service: Deactivated successfully.900machine # [ 9.023970] systemd[1]: Stopped Name Service Cache Daemon (nsncd).901machine # [ 9.025382] systemd[1]: Starting Name Service Cache Daemon (nsncd)...902machine # [ 9.027264] systemd[1]: Starting Virtual Console Setup...903machine # [ 9.031344] nsncd[592]: Sep 04 02:36:28.194 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"904machine # [ 9.037253] systemd[1]: Started D-Bus System Message Bus.905machine # [ 9.043390] dbus-broker-launch[516]: Ready906machine # [ 9.047627] systemd[1]: Finished resolvconf update.907machine # [ 9.051505] systemd[1]: Reached target Preparation for Network.908machine # [ 9.053167] systemd[1]: Starting DHCP Client...909machine # [ 9.056057] systemd[1]: Starting Address configuration of eth1...910machine # [ 9.057740] systemd[1]: Starting Extra networking commands....911machine # [ 9.060311] systemd[1]: Started Name Service Cache Daemon (nsncd).912machine # [ 9.061903] systemd[1]: Reached target Host and Network Name Lookups.913machine # [ 9.064621] systemd[1]: Reached target User and Group Name Lookups.914machine # [ 9.068260] systemd-logind[537]: New seat seat0.915machine # [ 9.070765] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.916machine # [ 9.073269] systemd[1]: Stopped Virtual Console Setup.917machine # [ 9.074541] systemd-logind[537]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)918machine # [ 9.075792] systemd-logind[537]: Watching system buttons on /dev/input/event2 (Power Button)919machine # [ 9.077385] systemd[1]: Started User Login Management.920machine # [ 9.228891] ppdev: user-space parallel port driver921machine # [ 9.086091] systemd[1]: Starting linger-users.service...922machine # [ 9.094337] systemd[1]: Starting Virtual Console Setup...923machine # [ 9.118466] network-addresses-eth1-start[606]: adding address 192.168.1.1/24... done924machine # [ 9.144038] network-addresses-eth1-start[606]: adding address 2001:db8:1::1/64... done925machine # [ 9.176622] systemd[1]: Finished Address configuration of eth1.926machine # [ 9.345730] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)927machine # [ 9.236588] systemd[1]: Created slice Slice /user/1001.928machine # [ 9.244030] systemd[1]: Starting User Runtime Directory /run/user/1001...929machine # [ 9.268963] systemd-logind[537]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)930machine # [ 9.426063] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)931machine # [ 9.283292] systemd[1]: linger-users.service: Deactivated successfully.932machine # [ 9.286142] systemd[1]: Finished linger-users.service.933machine # [ 9.317808] systemd[1]: Finished Extra networking commands..934machine # [ 9.323285] systemd[1]: Reached target Network.935machine # [ 9.329823] systemd[1]: Starting Permit User Sessions...936machine # [ 9.345622] dhcpcd[631]: dhcpcd-10.3.2 starting937machine # [ 9.359463] systemd[1]: Finished User Runtime Directory /run/user/1001.938machine # [ 9.362778] dhcpcd[692]: dev: loaded udev939machine # [ 9.386161] systemd[1]: Starting User Manager for UID 1001...940machine # [ 9.552005] 8021q: 802.1Q VLAN Support v1.8941machine # [ 9.552465] 8021q: adding VLAN 0 to HW filter on device eth1942machine # [ 9.415214] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.943machine # [ 9.416271] systemd[1]: Stopped Virtual Console Setup.944machine # [ 9.426808] systemd[1]: Starting Virtual Console Setup...945machine # [ 9.481054] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.946machine # [ 9.495571] systemd[1]: Finished Permit User Sessions.947machine # [ 9.499376] systemd[1]: Started Getty on tty1.948machine # [ 9.500966] systemd[1]: Reached target Login Prompts.949machine # [ 9.538767] (systemd)[694]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)950machine # [ 9.592951] systemd-logind[537]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.951machine # [ 9.742058] cfg80211: Loading compiled-in X.509 certificates for regulatory database952machine # [ 9.778590] kvm_amd: TSC scaling supported953machine # [ 9.779216] kvm_amd: Nested Virtualization enabled954machine # [ 9.780247] kvm_amd: Nested Paging enabled955machine # [ 9.781103] kvm_amd: LBR virtualization supported956machine # [ 9.781605] kvm_amd: Virtual VMLOAD VMSAVE supported957machine # [ 9.782339] kvm_amd: Virtual GIF supported958machine # [ 9.783260] kvm_amd: Virtual NMI enabled959machine # [ 9.786370] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'960machine # [ 9.787224] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'961machine # [ 9.790600] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2962machine # [ 9.791502] cfg80211: failed to load regulatory.db963machine # [ 9.647540] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy964machine # [ 9.875080] EDAC MC: Ver: 3.0.0965machine # [ 9.895611] 8021q: adding VLAN 0 to HW filter on device eth0966machine # [ 9.752745] dhcpcd[692]: eth0: waiting for carrier967machine # [ 9.755255] dhcpcd[692]: libudev: received NULL device968machine # [ 9.756430] dhcpcd[692]: libudev: received NULL device969machine # [ 9.757466] dhcpcd[692]: eth0: carrier acquired970machine # [ 9.770688] dhcpcd[692]: DUID 00:01:00:01:32:2c:eb:2d:52:54:00:12:34:56971machine # [ 9.772257] dhcpcd[692]: eth0: IAID 00:12:34:56972machine # [ 9.772932] dhcpcd[692]: eth0: adding address fe80::5054:ff:fe12:3456973machine # [ 9.866765] systemd[694]: Queued start job for default target Main User Target.974machine # [ 9.869754] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy975machine # [ 9.872593] systemd[694]: Created slice User Application Slice.976machine # [ 9.873591] systemd[694]: Started Daily Cleanup of User's Temporary Directories.977machine # [ 9.875125] systemd[694]: Reached target Paths.978machine # [ 9.876489] systemd[694]: Reached target Timers.979machine # [ 9.878979] systemd[694]: Starting D-Bus User Message Bus Socket...980machine # [ 9.882131] systemd[694]: Starting Create User Files and Directories...981machine # [ 9.928486] systemd[694]: Finished Create User Files and Directories.982machine # [ 9.965911] systemd[694]: Listening on D-Bus User Message Bus Socket.983machine # [ 9.969662] systemd[694]: Reached target Sockets.984machine # [ 9.970351] systemd[694]: Reached target Basic System.985machine # [ 9.971903] systemd[1]: Started User Manager for UID 1001.986machine # [ 9.973856] systemd[694]: Starting Run user-specific NixOS activation...987machine # [ 10.011470] systemd[694]: Finished Run user-specific NixOS activation.988machine # [ 10.012855] systemd[694]: Reached target Main User Target.989machine # [ 10.013873] systemd[694]: Startup finished in 390ms.990machine # [ 10.068357] systemd-vconsole-setup[699]: Configuration of first virtual console was skipped, ignoring remaining ones.991machine # [ 10.073231] systemd[1]: Finished Virtual Console Setup.992machine # [ 10.904339] dhcpcd[692]: eth0: soliciting a DHCP lease993machine # [ 11.067970] NET: Registered PF_PACKET protocol family994machine # [ 10.927582] dhcpcd[692]: eth0: offered 10.0.2.15 from 10.0.2.2995machine # [ 10.930173] dhcpcd[692]: eth0: probing address 10.0.2.15/24996machine # [ 11.926744] dhcpcd[692]: eth0: soliciting an IPv6 router997machine # [ 11.929268] dhcpcd[692]: eth0: Router Advertisement from fe80::2998machine # [ 11.931475] dhcpcd[692]: eth0: adding address fec0::5054:ff:fe12:3456/64999machine # [ 11.933613] dhcpcd[692]: eth0: adding route to fec0::/641000machine # [ 11.935374] dhcpcd[692]: eth0: adding default route via fe80::21001machine # [ 15.707487] dhcpcd[692]: eth0: leased 10.0.2.15 for 86400 seconds1002machine # [ 15.710724] dhcpcd[692]: eth0: adding route to 10.0.2.0/241003machine # [ 15.712926] dhcpcd[692]: eth0: adding default route via 10.0.2.21004machine # [ 15.839187] systemd[1]: Started DHCP Client.1005machine # [ 15.841265] systemd[1]: Reached target Multi-User System.1006machine # [ 15.842229] systemd[1]: Startup finished in 852ms (kernel) + 3.993s (initrd) + 10.994s (userspace) = 15.841s.1007machine: (finished: waiting for unit multi-user.target, in 17.39 seconds)1008machine: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/bin/switch-to-configuration test1009machine # Checking switch inhibitors... done1010machine # [ 17.068193] nixos[853]: switching to system configuration /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test1011machine # [ 17.070799] systemd[1]: Stopped target Local File Systems.1012machine # [ 17.072426] systemd[1]: Stopped target Remote File Systems.1013machine # activating the configuration...1014machine # setting up /etc...1015machine # [ 17.485958] systemd[1]: Reload requested from client PID 853 ('.switch-to-conf') (unit backdoor.service)...1016machine # [ 17.487754] systemd[1]: Reloading...1017machine # [ 18.063091] systemd[1]: Reloading finished in 572 ms.1018machine # reloading user units for usertest...1019machine # [ 18.096184] systemd[694]: Created slice User Core Session Slice.1020machine # [ 18.098643] systemd[694]: Starting D-Bus User Message Bus...1021machine # [ 18.159686] dbus-broker-launch[923]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/nxbpk401an3y90294yk3vj386qpkvmng-system-path/share/dbus-1/services/org.freedesktop.import1.service'1022machine # [ 18.162381] dbus-broker-launch[923]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/nxbpk401an3y90294yk3vj386qpkvmng-system-path/share/dbus-1/services/org.freedesktop.machine1.service'1023machine # [ 18.165387] dbus-broker-launch[923]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/nxbpk401an3y90294yk3vj386qpkvmng-system-path/share/dbus-1/services/org.freedesktop.portable1.service'1024machine # [ 18.168487] dbus-broker-launch[923]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/nxbpk401an3y90294yk3vj386qpkvmng-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'1025machine # [ 18.171828] dbus-broker-launch[923]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored1026machine # [ 18.173379] dbus-broker-launch[923]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored1027machine # [ 18.175071] systemd[694]: Started D-Bus User Message Bus.1028machine # [ 18.183989] dbus-broker-launch[923]: Ready1029machine # [ 18.299741] systemd[694]: Reexecution requested from client PID 922 ('switch-to-confi')...1030machine # [ 18.301128] systemd[694]: Reexecuting.1031machine # [ 18.331981] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1032machine # [ 18.507463] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1033machine # [ 18.512315] systemd[694]: Reload requested from client PID 922 ('switch-to-confi')...1034machine # [ 18.513918] systemd[694]: Reloading...1035machine # [ 18.680856] systemd[694]: Reloading finished in 165 ms.1036machine # [ 18.685809] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1037machine # restarting the following user units: nixos-activation.service1038machine # [ 18.693142] systemd[694]: Stopped Run user-specific NixOS activation.1039machine # [ 18.694021] systemd[694]: Stopping Run user-specific NixOS activation...1040machine # [ 18.697168] systemd[694]: Starting Run user-specific NixOS activation...1041machine # [ 18.737669] systemd[694]: Finished Run user-specific NixOS activation.1042machine # restarting sysinit-reactivation.target1043machine # [ 18.753348] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....1044machine # [ 18.868211] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..1045machine # [ 18.870244] systemd[1]: Reached target Reactivate sysinit units.1046machine # [ 18.873751] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1047machine # [ 18.875960] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1048machine # [ 18.879677] systemd[1]: Reached target Remote File Systems.1049machine # [ 18.884095] systemd[1]: Reached target Local File Systems.1050machine # [ 18.884958] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1051machine # [ 18.888264] systemd[1]: Starting Create SUID/SGID Wrappers...1052machine # [ 18.889372] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1053machine # [ 18.892642] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671054machine # [ 18.895798] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1055machine # [ 18.898043] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1056machine # [ 18.900364] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1057machine # [ 19.227395] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1058machine # [ 19.232146] systemd[1]: Finished Create SUID/SGID Wrappers.1059machine # [ 19.234684] systemd[1]: Starting linger-users.service...1060machine # [ 19.294282] systemd[1]: linger-users.service: Deactivated successfully.1061machine # [ 19.295990] systemd[1]: Finished linger-users.service.1062machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service1063machine # [ 19.557587] nixos[853]: finished switching to system configuration /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test1064machine: (finished: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/bin/switch-to-configuration test, in 3.11 seconds)1065machine: must succeed: rm /etc/NIXOS1066machine: (finished: must succeed: rm /etc/NIXOS, in 0.04 seconds)1067machine: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test/bin/switch-to-configuration test1068machine # this should succeed (config: /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test, action: test)1069machine # Checking switch inhibitors... done1070machine # [ 20.279657] nixos[1001]: switching to system configuration /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test1071machine # activating the configuration...1072machine # [ 20.283540] systemd[1]: Stopped target Remote File Systems.1073machine # [ 20.284463] systemd[1]: Stopped target Local File Systems.1074machine # removing user ‘usertest’1075machine # setting up /etc...1076machine # [ 20.709536] systemd[1]: Reload requested from client PID 1001 ('.switch-to-conf') (unit backdoor.service)...1077machine # [ 20.711098] systemd[1]: Reloading...1078machine # [ 21.332324] systemd[1]: Reloading finished in 619 ms.1079machine # reloading user units for usertest...1080machine # [ 21.474258] systemd[694]: Reexecution requested from client PID 1072 ('switch-to-confi')...1081machine # [ 21.475584] systemd[694]: Reexecuting.1082machine # [ 21.508696] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1083machine # [ 21.670280] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1084machine # [ 21.675964] systemd[694]: Reload requested from client PID 1072 ('switch-to-confi')...1085machine # [ 21.677320] systemd[694]: Reloading...1086machine # [ 21.826660] systemd[694]: Reloading finished in 147 ms.1087machine # [ 21.830825] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1088machine # restarting the following user units: nixos-activation.service1089machine # [ 21.838296] systemd[694]: Stopped Run user-specific NixOS activation.1090machine # [ 21.839654] systemd[694]: Stopping Run user-specific NixOS activation...1091machine # [ 21.842589] systemd[694]: Starting Run user-specific NixOS activation...1092machine # [ 21.884370] systemd[694]: Finished Run user-specific NixOS activation.1093machine # restarting sysinit-reactivation.target1094machine # [ 21.888495] systemd[1]: Stopped target Reactivate sysinit units.1095machine # [ 21.889729] systemd[1]: Stopping Reactivate sysinit units...1096machine # [ 21.890701] systemd[1]: Reached target Reactivate sysinit units.1097machine # reloading the following units: dbus-broker.service1098machine # [ 21.893668] dbus-broker-launch[516]: Caught SIGHUP, trigger reload.1099machine # [ 21.894569] systemd[1]: Reloading D-Bus System Message Bus...1100machine # [ 21.918726] dbus-broker-launch[516]: Looking up NSS user entry for 'systemd-timesync'...1101machine # [ 21.920625] dbus-broker-launch[516]: NSS returned no entry for 'systemd-timesync'1102machine # [ 21.921713] dbus-broker-launch[516]: Invalid user-name in /nix/store/0n1xh0s1wqya4gyrk665anqq5xkl5bll-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1103machine # [ 21.949591] systemd[1]: Reloaded D-Bus System Message Bus.1104machine # restarting the following units: network-addresses-eth1.service1105machine # [ 21.955832] systemd[1]: Stopping Address configuration of eth1...1106machine # [ 22.006538] network-addresses-eth1-pre-stop[1084]: deleting address 192.168.1.1/24... done1107machine # [ 22.019897] network-addresses-eth1-pre-stop[1084]: deleting address 2001:db8:1::1/64... done1108machine # [ 22.029590] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1109machine # [ 22.031215] systemd[1]: Stopped Address configuration of eth1.1110machine # [ 22.035509] systemd[1]: Starting Address configuration of eth1...1111machine # [ 22.110720] network-addresses-eth1-start[1089]: adding address 192.168.1.2/24... done1112machine # [ 22.123559] network-addresses-eth1-start[1089]: adding address 2001:db8:1::2/64... done1113machine # [ 22.139319] systemd[1]: Finished Address configuration of eth1.1114machine # [ 22.146260] systemd[1]: Reached target Local File Systems.1115machine # [ 22.148258] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1116machine # [ 22.152721] systemd[1]: Starting Create SUID/SGID Wrappers...1117machine # [ 22.153502] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1118machine # [ 22.154607] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671119machine # [ 22.157857] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1120machine # [ 22.160390] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1121machine # [ 22.162392] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1122machine # [ 22.164657] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1123machine # [ 22.167150] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1124machine # [ 22.169473] systemd[1]: Reached target Remote File Systems.1125machine # [ 22.575748] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1126machine # [ 22.579104] systemd[1]: Finished Create SUID/SGID Wrappers.1127machine # [ 22.582352] systemd[1]: Starting linger-users.service...1128machine # [ 22.625308] linger-users-start[1162]: id: ‘usertest’: no such user1129machine # [ 22.626970] linger-users-start[1160]: Removing linger for missing user usertest1130machine # [ 22.635715] systemd[1]: linger-users.service: Deactivated successfully.1131machine # [ 22.637308] systemd[1]: Finished linger-users.service.1132machine # [ 22.895485] nixos[1001]: finished switching to system configuration /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test1133machine: (finished: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test/bin/switch-to-configuration test, in 3.29 seconds)1134subtest: pre-switch checks1135machine: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test/bin/switch-to-configuration check1136machine # this should succeed (config: /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test, action: check)1137machine # Checking switch inhibitors... done1138machine: (finished: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test/bin/switch-to-configuration check, in 0.08 seconds)1139machine: must fail: /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test/specialisation/failingCheck/bin/switch-to-configuration check 2>&11140machine: (finished: must fail: /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test/specialisation/failingCheck/bin/switch-to-configuration check 2>&1, in 0.05 seconds)1141machine: must fail: /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test/specialisation/failingMidCheck/bin/switch-to-configuration check 2>&11142machine: (finished: must fail: /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test/specialisation/failingMidCheck/bin/switch-to-configuration check 2>&1, in 0.05 seconds)1143(finished: subtest: pre-switch checks, in 0.17 seconds)1144subtest: switch inhibitors1145machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/no_inhibitors/bin/switch-to-configuration switch 2>&11146machine # [ 23.684110] nixos[1190]: switching to system configuration /nix/store/mj6v4llphnny5dgg42gz8hivy3g6ncv1-nixos-system-machine-test1147machine # [ 23.686541] systemd[1]: Stopped target Local File Systems.1148machine # [ 23.688272] systemd[1]: Stopped target Remote File Systems.1149machine # [ 24.112862] systemd[1]: Reload requested from client PID 1190 ('.switch-to-conf') (unit backdoor.service)...1150machine # [ 24.114555] systemd[1]: Reloading...1151machine # [ 24.667990] systemd[1]: Reloading finished in 550 ms.1152machine # [ 24.805262] systemd[694]: Reexecution requested from client PID 1260 ('switch-to-confi')...1153machine # [ 24.806519] systemd[694]: Reexecuting.1154machine # [ 24.836364] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1155machine # [ 25.007700] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1156machine # [ 25.013119] systemd[694]: Reload requested from client PID 1260 ('switch-to-confi')...1157machine # [ 25.014847] systemd[694]: Reloading...1158machine # [ 25.184616] systemd[694]: Reloading finished in 168 ms.1159machine # [ 25.189844] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1160machine # [ 25.197250] systemd[694]: Stopped Run user-specific NixOS activation.1161machine # [ 25.199125] systemd[694]: Stopping Run user-specific NixOS activation...1162machine # [ 25.201628] systemd[694]: Starting Run user-specific NixOS activation...1163machine # [ 25.242408] systemd[694]: Finished Run user-specific NixOS activation.1164machine # [ 25.246080] systemd[1]: Stopped target Reactivate sysinit units.1165machine # [ 25.247770] systemd[1]: Stopping Reactivate sysinit units...1166machine # [ 25.250109] systemd[1]: Reached target Reactivate sysinit units.1167machine # [ 25.251621] dbus-broker-launch[516]: Caught SIGHUP, trigger reload.1168machine # [ 25.252641] systemd[1]: Reloading D-Bus System Message Bus...1169machine # [ 25.267704] dbus-broker-launch[516]: Looking up NSS user entry for 'systemd-timesync'...1170machine # [ 25.269904] dbus-broker-launch[516]: NSS returned no entry for 'systemd-timesync'1171machine # [ 25.271044] dbus-broker-launch[516]: Invalid user-name in /nix/store/nxbpk401an3y90294yk3vj386qpkvmng-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1172machine # [ 25.293662] systemd[1]: Reloaded D-Bus System Message Bus.1173machine # [ 25.298673] systemd[1]: Stopping Address configuration of eth1...1174machine # [ 25.345757] network-addresses-eth1-pre-stop[1272]: deleting address 192.168.1.2/24... done1175machine # [ 25.358762] network-addresses-eth1-pre-stop[1272]: deleting address 2001:db8:1::2/64... done1176machine # [ 25.368538] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1177machine # [ 25.370156] systemd[1]: Stopped Address configuration of eth1.1178machine # [ 25.374511] systemd[1]: Starting Address configuration of eth1...1179machine # [ 25.447304] network-addresses-eth1-start[1277]: adding address 192.168.1.1/24... done1180machine # [ 25.460484] network-addresses-eth1-start[1277]: adding address 2001:db8:1::1/64... done1181machine # [ 25.475360] systemd[1]: Finished Address configuration of eth1.1182machine # [ 25.478168] systemd[1]: Reached target Local File Systems.1183machine # [ 25.481844] systemd[1]: Reached target Remote File Systems.1184machine # [ 25.485871] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1185machine # [ 25.491350] systemd[1]: Starting Create SUID/SGID Wrappers...1186machine # [ 25.492451] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1187machine # [ 25.494651] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671188machine # [ 25.497250] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1189machine # [ 25.499285] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1190machine # [ 25.501963] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1191machine # [ 25.504066] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1192machine # [ 25.505439] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1193machine # [ 25.851798] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1194machine # [ 25.855177] systemd[1]: Finished Create SUID/SGID Wrappers.1195machine # [ 25.858234] systemd[1]: Starting linger-users.service...1196machine # [ 25.901823] systemd[1]: linger-users.service: Deactivated successfully.1197machine # [ 25.904210] systemd[1]: Finished linger-users.service.1198machine # [ 26.161336] nixos[1190]: finished switching to system configuration /nix/store/mj6v4llphnny5dgg42gz8hivy3g6ncv1-nixos-system-machine-test1199machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/no_inhibitors/bin/switch-to-configuration switch 2>&1, in 3.09 seconds)1200machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/inhibitors/bin/switch-to-configuration switch 2>&11201machine # [ 26.911271] nixos[1347]: switching to system configuration /nix/store/6cswgqzq8azflxb6ddrb2zyrc54wznpg-nixos-system-machine-test1202machine # [ 26.914224] systemd[1]: Stopped target Local File Systems.1203machine # [ 26.915414] systemd[1]: Stopped target Remote File Systems.1204machine # [ 27.515586] systemd[1]: Reload requested from client PID 1347 ('.switch-to-conf') (unit backdoor.service)...1205machine # [ 27.518219] systemd[1]: Reloading...1206machine # [ 28.366349] systemd[1]: Reloading finished in 843 ms.1207machine # [ 28.572611] systemd[694]: Reexecution requested from client PID 1417 ('switch-to-confi')...1208machine # [ 28.575973] systemd[694]: Reexecuting.1209machine # [ 28.611396] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1210machine # [ 28.855434] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1211machine # [ 28.862239] systemd[694]: Reload requested from client PID 1417 ('switch-to-confi')...1212machine # [ 28.864130] systemd[694]: Reloading...1213machine # [ 29.107148] systemd[694]: Reloading finished in 239 ms.1214machine # [ 29.111866] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1215machine # [ 29.120117] systemd[694]: Stopped Run user-specific NixOS activation.1216machine # [ 29.121474] systemd[694]: Stopping Run user-specific NixOS activation...1217machine # [ 29.125912] systemd[694]: Starting Run user-specific NixOS activation...1218machine # [ 29.171966] systemd[694]: Finished Run user-specific NixOS activation.1219machine # [ 29.175566] systemd[1]: Stopped target Reactivate sysinit units.1220machine # [ 29.176936] systemd[1]: Stopping Reactivate sysinit units...1221machine # [ 29.178134] systemd[1]: Reached target Reactivate sysinit units.1222machine # [ 29.180473] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1223machine # [ 29.182156] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1224machine # [ 29.186752] systemd[1]: Reached target Local File Systems.1225machine # [ 29.190511] systemd[1]: Reached target Remote File Systems.1226machine # [ 29.194350] systemd[1]: Starting linger-users.service...1227machine # [ 29.200215] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1228machine # [ 29.203062] systemd[1]: Starting Create SUID/SGID Wrappers...1229machine # [ 29.203835] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1230machine # [ 29.205049] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671231machine # [ 29.206993] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1232machine # [ 29.208482] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1233machine # [ 29.211119] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1234machine # [ 29.269717] systemd[1]: linger-users.service: Deactivated successfully.1235machine # [ 29.270909] systemd[1]: Finished linger-users.service.1236machine # [ 29.552811] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1237machine # [ 29.554508] systemd[1]: Finished Create SUID/SGID Wrappers.1238machine # [ 29.809589] nixos[1347]: finished switching to system configuration /nix/store/6cswgqzq8azflxb6ddrb2zyrc54wznpg-nixos-system-machine-test1239machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/inhibitors/bin/switch-to-configuration switch 2>&1, in 3.64 seconds)1240machine: must fail: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/inhibitors_changed/bin/switch-to-configuration switch 2>&11241machine: (finished: must fail: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/inhibitors_changed/bin/switch-to-configuration switch 2>&1, in 0.05 seconds)1242machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/inhibitors_changed/bin/switch-to-configuration boot 2>&11243machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/inhibitors_changed/bin/switch-to-configuration boot 2>&1, in 0.04 seconds)1244machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/inhibitors_changed/bin/switch-to-configuration dry-activate 2>&11245machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/inhibitors_changed/bin/switch-to-configuration dry-activate 2>&1, in 0.60 seconds)1246machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/inhibitors_new/bin/switch-to-configuration switch 2>&11247machine # [ 31.082722] nixos[1513]: switching to system configuration /nix/store/43zdjpmybrrvjiz27azv0d9w2qf93wzq-nixos-system-machine-test1248machine # [ 31.085222] systemd[1]: Stopped target Local File Systems.1249machine # [ 31.087322] systemd[1]: Stopped target Remote File Systems.1250machine # [ 31.524325] systemd[1]: Reload requested from client PID 1513 ('.switch-to-conf') (unit backdoor.service)...1251machine # [ 31.525880] systemd[1]: Reloading...1252machine # [ 32.091086] systemd[1]: Reloading finished in 562 ms.1253machine # [ 32.229166] systemd[694]: Reexecution requested from client PID 1583 ('switch-to-confi')...1254machine # [ 32.231491] systemd[694]: Reexecuting.1255machine # [ 32.260403] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1256machine # [ 32.419106] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1257machine # [ 32.425107] systemd[694]: Reload requested from client PID 1583 ('switch-to-confi')...1258machine # [ 32.426856] systemd[694]: Reloading...1259machine # [ 32.587053] systemd[694]: Reloading finished in 158 ms.1260machine # [ 32.591838] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1261machine # [ 32.599062] systemd[694]: Stopped Run user-specific NixOS activation.1262machine # [ 32.599919] systemd[694]: Stopping Run user-specific NixOS activation...1263machine # [ 32.603178] systemd[694]: Starting Run user-specific NixOS activation...1264machine # [ 32.644068] systemd[694]: Finished Run user-specific NixOS activation.1265machine # [ 32.647436] systemd[1]: Stopped target Reactivate sysinit units.1266machine # [ 32.648765] systemd[1]: Stopping Reactivate sysinit units...1267machine # [ 32.649978] systemd[1]: Reached target Reactivate sysinit units.1268machine # [ 32.655144] systemd[1]: Reached target Local File Systems.1269machine # [ 32.655955] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1270machine # [ 32.660704] systemd[1]: Starting Create SUID/SGID Wrappers...1271machine # [ 32.661495] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1272machine # [ 32.662696] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671273machine # [ 32.665763] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1274machine # [ 32.667632] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1275machine # [ 32.669746] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1276machine # [ 32.674522] systemd[1]: Reached target Remote File Systems.1277machine # [ 32.679046] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1278machine # [ 32.680316] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1279machine # [ 33.005647] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1280machine # [ 33.006960] systemd[1]: Finished Create SUID/SGID Wrappers.1281machine # [ 33.011746] systemd[1]: Starting linger-users.service...1282machine # [ 33.065445] systemd[1]: linger-users.service: Deactivated successfully.1283machine # [ 33.067073] systemd[1]: Finished linger-users.service.1284machine # [ 33.324436] nixos[1513]: finished switching to system configuration /nix/store/43zdjpmybrrvjiz27azv0d9w2qf93wzq-nixos-system-machine-test1285machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/inhibitors_new/bin/switch-to-configuration switch 2>&1, in 2.82 seconds)1286machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/no_inhibitors/bin/switch-to-configuration switch 2>&11287machine # [ 34.034048] nixos[1655]: switching to system configuration /nix/store/mj6v4llphnny5dgg42gz8hivy3g6ncv1-nixos-system-machine-test1288machine # [ 34.037527] systemd[1]: Stopped target Remote File Systems.1289machine # [ 34.038426] systemd[1]: Stopped target Local File Systems.1290machine # [ 34.456815] systemd[1]: Reload requested from client PID 1655 ('.switch-to-conf') (unit backdoor.service)...1291machine # [ 34.458440] systemd[1]: Reloading...1292machine # [ 34.943530] systemd[1]: Reloading finished in 483 ms.1293machine # [ 35.060304] systemd[694]: Reexecution requested from client PID 1725 ('switch-to-confi')...1294machine # [ 35.062583] systemd[694]: Reexecuting.1295machine # [ 35.087892] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1296machine # [ 35.234290] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1297machine # [ 35.240635] systemd[694]: Reload requested from client PID 1725 ('switch-to-confi')...1298machine # [ 35.241922] systemd[694]: Reloading...1299machine # [ 35.398584] systemd[694]: Reloading finished in 155 ms.1300machine # [ 35.402806] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1301machine # [ 35.409217] systemd[694]: Stopped Run user-specific NixOS activation.1302machine # [ 35.411104] systemd[694]: Stopping Run user-specific NixOS activation...1303machine # [ 35.413459] systemd[694]: Starting Run user-specific NixOS activation...1304machine # [ 35.450094] systemd[694]: Finished Run user-specific NixOS activation.1305machine # [ 35.453427] systemd[1]: Stopped target Reactivate sysinit units.1306machine # [ 35.455092] systemd[1]: Stopping Reactivate sysinit units...1307machine # [ 35.455946] systemd[1]: Reached target Reactivate sysinit units.1308machine # [ 35.457769] systemd[1]: Reached target Local File Systems.1309machine # [ 35.460161] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1310machine # [ 35.464379] systemd[1]: Starting Create SUID/SGID Wrappers...1311machine # [ 35.465387] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1312machine # [ 35.466607] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671313machine # [ 35.468553] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1314machine # [ 35.470090] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1315machine # [ 35.471352] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1316machine # [ 35.474764] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1317machine # [ 35.476750] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1318machine # [ 35.481536] systemd[1]: Reached target Remote File Systems.1319machine # [ 35.755462] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1320machine # [ 35.757101] systemd[1]: Finished Create SUID/SGID Wrappers.1321machine # [ 35.761776] systemd[1]: Starting linger-users.service...1322machine # [ 35.807963] systemd[1]: linger-users.service: Deactivated successfully.1323machine # [ 35.809481] systemd[1]: Finished linger-users.service.1324machine # [ 36.066985] nixos[1655]: finished switching to system configuration /nix/store/mj6v4llphnny5dgg42gz8hivy3g6ncv1-nixos-system-machine-test1325machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/no_inhibitors/bin/switch-to-configuration switch 2>&1, in 2.74 seconds)1326(finished: subtest: switch inhibitors, in 13.00 seconds)1327subtest: actions1328machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/modifiedSystemConf/bin/switch-to-configuration boot 2>&11329machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/modifiedSystemConf/bin/switch-to-configuration boot 2>&1, in 0.07 seconds)1330machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/bin/switch-to-configuration switch 2>&11331machine # [ 36.662219] nixos[1804]: switching to system configuration /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test1332machine # [ 36.664525] systemd[1]: Stopped target Remote File Systems.1333machine # [ 36.666258] systemd[1]: Stopped target Local File Systems.1334machine # [ 37.044549] systemd[1]: Reload requested from client PID 1804 ('.switch-to-conf') (unit backdoor.service)...1335machine # [ 37.046212] systemd[1]: Reloading...1336machine # [ 37.517688] systemd[1]: Reloading finished in 469 ms.1337machine # [ 37.634296] systemd[694]: Reexecution requested from client PID 1874 ('switch-to-confi')...1338machine # [ 37.636393] systemd[694]: Reexecuting.1339machine # [ 37.664254] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1340machine # [ 37.816826] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1341machine # [ 37.822687] systemd[694]: Reload requested from client PID 1874 ('switch-to-confi')...1342machine # [ 37.824134] systemd[694]: Reloading...1343machine # [ 37.977269] systemd[694]: Reloading finished in 151 ms.1344machine # [ 37.981817] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1345machine # [ 37.988365] systemd[694]: Stopped Run user-specific NixOS activation.1346machine # [ 37.990115] systemd[694]: Stopping Run user-specific NixOS activation...1347machine # [ 37.992607] systemd[694]: Starting Run user-specific NixOS activation...1348machine # [ 38.029690] systemd[694]: Finished Run user-specific NixOS activation.1349machine # [ 38.033490] systemd[1]: Stopped target Reactivate sysinit units.1350machine # [ 38.035329] systemd[1]: Stopping Reactivate sysinit units...1351machine # [ 38.036826] systemd[1]: Reached target Reactivate sysinit units.1352machine # [ 38.039640] systemd[1]: Reached target Local File Systems.1353machine # [ 38.042406] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1354machine # [ 38.044349] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1355machine # [ 38.046367] systemd[1]: Reached target Remote File Systems.1356machine # [ 38.054088] systemd[1]: Starting linger-users.service...1357machine # [ 38.059202] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1358machine # [ 38.063412] systemd[1]: Starting Create SUID/SGID Wrappers...1359machine # [ 38.064248] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1360machine # [ 38.067114] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671361machine # [ 38.069110] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1362machine # [ 38.070482] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1363machine # [ 38.071596] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1364machine # [ 38.126286] systemd[1]: linger-users.service: Deactivated successfully.1365machine # [ 38.127713] systemd[1]: Finished linger-users.service.1366machine # [ 38.405300] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1367machine # [ 38.406941] systemd[1]: Finished Create SUID/SGID Wrappers.1368machine # [ 38.661762] nixos[1804]: finished switching to system configuration /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test1369machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/bin/switch-to-configuration switch 2>&1, in 2.52 seconds)1370machine: must fail: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/bin/switch-to-configuration broken-action 2>&11371machine: (finished: must fail: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/bin/switch-to-configuration broken-action 2>&1, in 0.04 seconds)1372machine: must fail: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/bin/switch-to-configuration 2>&11373machine: (finished: must fail: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/bin/switch-to-configuration 2>&1, in 0.03 seconds)1374(finished: subtest: actions, in 2.66 seconds)1375subtest: init interface version1376machine: must fail: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/brokenInitInterface/bin/switch-to-configuration test 2>&11377machine: (finished: must fail: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/brokenInitInterface/bin/switch-to-configuration test 2>&1, in 0.06 seconds)1378(finished: subtest: init interface version, in 0.06 seconds)1379subtest: systemd restarts1380machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/modifiedSystemConf/bin/switch-to-configuration test 2>&11381machine # [ 39.438738] nixos[1961]: switching to system configuration /nix/store/3y372w3l94y85b4wbzsdxj7p332dah8y-nixos-system-machine-test1382machine # [ 39.441241] systemd[1]: Stopped target Local File Systems.1383machine # [ 39.443338] systemd[1]: Stopped target Remote File Systems.1384machine # [ 39.956736] systemd[1]: Reexecution requested from client PID 1961 ('.switch-to-conf') (unit backdoor.service)...1385machine # [ 39.960657] systemd[1]: Reexecuting.1386machine # [ 40.263857] systemd[1]: systemd 262-175b52e running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -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)1387machine # [ 40.271993] systemd[1]: Detected virtualization kvm.1388machine # [ 40.273431] systemd[1]: Detected architecture x86-64.1389machine # [ 40.374813] systemd[1]: bpf-restrict-fs: LSM BPF program attached1390machine # [ 40.781567] systemd[1]: Reload requested from client PID 1961 ('.switch-to-conf') (unit backdoor.service)...1391machine # [ 40.783146] systemd[1]: Reloading...1392machine # [ 41.358901] systemd[1]: Reloading finished in 573 ms.1393machine # [ 41.492904] systemd[694]: Reexecution requested from client PID 2064 ('switch-to-confi')...1394machine # [ 41.495555] systemd[694]: Reexecuting.1395machine # [ 41.524562] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1396machine # [ 41.694240] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1397machine # [ 41.699548] systemd[694]: Reload requested from client PID 2064 ('switch-to-confi')...1398machine # [ 41.701270] systemd[694]: Reloading...1399machine # [ 41.873403] systemd[694]: Reloading finished in 170 ms.1400machine # [ 41.879106] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1401machine # [ 41.886685] systemd[694]: Stopped Run user-specific NixOS activation.1402machine # [ 41.888186] systemd[694]: Stopping Run user-specific NixOS activation...1403machine # [ 41.891334] systemd[694]: Starting Run user-specific NixOS activation...1404machine # [ 41.933924] systemd[694]: Finished Run user-specific NixOS activation.1405machine # [ 41.937612] systemd[1]: Stopped target Reactivate sysinit units.1406machine # [ 41.939625] systemd[1]: Stopping Reactivate sysinit units...1407machine # [ 41.940622] systemd[1]: Reached target Reactivate sysinit units.1408machine # [ 41.942318] systemd[1]: Reached target Remote File Systems.1409machine # [ 41.949520] systemd[1]: Starting linger-users.service...1410machine # [ 41.951632] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1411machine # [ 41.953567] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1412machine # [ 41.960575] systemd[1]: Reached target Local File Systems.1413machine # [ 41.964168] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1414machine # [ 41.970165] systemd[1]: Starting Create SUID/SGID Wrappers...1415machine # [ 41.971068] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1416machine # [ 41.972289] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671417machine # [ 41.975663] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1418machine # [ 41.977255] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1419machine # [ 41.978873] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1420machine # [ 42.030671] systemd[1]: linger-users.service: Deactivated successfully.1421machine # [ 42.031762] systemd[1]: Finished linger-users.service.1422machine # [ 42.327952] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1423machine # [ 42.329785] systemd[1]: Finished Create SUID/SGID Wrappers.1424machine # [ 42.586336] nixos[1961]: finished switching to system configuration /nix/store/3y372w3l94y85b4wbzsdxj7p332dah8y-nixos-system-machine-test1425machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/modifiedSystemConf/bin/switch-to-configuration test 2>&1, in 3.80 seconds)1426(finished: subtest: systemd restarts, in 3.80 seconds)1427subtest: continuing from an aborted switch1428machine: must succeed: echo dbus-broker.service > /run/nixos/start-list1429machine: (finished: must succeed: echo dbus-broker.service > /run/nixos/start-list, in 0.03 seconds)1430machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/modifiedSystemConf/bin/switch-to-configuration test 2>&11431machine # [ 43.231368] nixos[2139]: switching to system configuration /nix/store/3y372w3l94y85b4wbzsdxj7p332dah8y-nixos-system-machine-test1432machine # [ 43.234983] systemd[1]: Stopped target Remote File Systems.1433machine # [ 43.235859] systemd[1]: Stopped target Local File Systems.1434machine # [ 43.660683] systemd[1]: Reload requested from client PID 2139 ('.switch-to-conf') (unit backdoor.service)...1435machine # [ 43.662396] systemd[1]: Reloading...1436machine # [ 44.238509] systemd[1]: Reloading finished in 573 ms.1437machine # [ 44.375311] systemd[694]: Reexecution requested from client PID 2208 ('switch-to-confi')...1438machine # [ 44.376673] systemd[694]: Reexecuting.1439machine # [ 44.406768] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1440machine # [ 44.572966] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1441machine # [ 44.578351] systemd[694]: Reload requested from client PID 2208 ('switch-to-confi')...1442machine # [ 44.579694] systemd[694]: Reloading...1443machine # [ 44.750501] systemd[694]: Reloading finished in 168 ms.1444machine # [ 44.755844] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1445machine # [ 44.764091] systemd[694]: Stopped Run user-specific NixOS activation.1446machine # [ 44.765066] systemd[694]: Stopping Run user-specific NixOS activation...1447machine # [ 44.767925] systemd[694]: Starting Run user-specific NixOS activation...1448machine # [ 44.807045] systemd[694]: Finished Run user-specific NixOS activation.1449machine # [ 44.810511] systemd[1]: Stopped target Reactivate sysinit units.1450machine # [ 44.811797] systemd[1]: Stopping Reactivate sysinit units...1451machine # [ 44.812846] systemd[1]: Reached target Reactivate sysinit units.1452machine # [ 44.814945] systemd[1]: Reached target Remote File Systems.1453machine # [ 44.820084] systemd[1]: Reached target Local File Systems.1454machine # [ 44.820927] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1455machine # [ 44.826244] systemd[1]: Starting Create SUID/SGID Wrappers...1456machine # [ 44.827157] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1457machine # [ 44.828397] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671458machine # [ 44.831855] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1459machine # [ 44.834103] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1460machine # [ 44.835960] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1461machine # [ 44.843755] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1462machine # [ 44.845091] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1463machine # [ 45.189677] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1464machine # [ 45.190839] systemd[1]: Finished Create SUID/SGID Wrappers.1465machine # [ 45.195771] systemd[1]: Starting linger-users.service...1466machine # [ 45.252609] systemd[1]: linger-users.service: Deactivated successfully.1467machine # [ 45.255308] systemd[1]: Finished linger-users.service.1468machine # [ 45.511749] nixos[2139]: finished switching to system configuration /nix/store/3y372w3l94y85b4wbzsdxj7p332dah8y-nixos-system-machine-test1469machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/modifiedSystemConf/bin/switch-to-configuration test 2>&1, in 2.90 seconds)1470(finished: subtest: continuing from an aborted switch, in 2.93 seconds)1471subtest: aborts on already locked lock file1472??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.1473 File "/nix/store/n2idrk0jvf991mc9cwbdlyb60018wbhz-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 391474machine # Could not acquire lock1475(finished: subtest: aborts on already locked lock file, in 0.09 seconds)1476subtest: fstab mounts1477machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/bin/switch-to-configuration test 2>&11478machine # [ 46.300258] nixos[2284]: switching to system configuration /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test1479machine # [ 46.302804] systemd[1]: Stopped target Remote File Systems.1480machine # [ 46.304590] systemd[1]: Stopped target Local File Systems.1481machine # [ 46.803621] systemd[1]: Reexecution requested from client PID 2284 ('.switch-to-conf') (unit backdoor.service)...1482machine # [ 46.809515] systemd[1]: Reexecuting.1483machine # [ 47.121326] systemd[1]: systemd 262-175b52e running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -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)1484machine # [ 47.139104] systemd[1]: Detected virtualization kvm.1485machine # [ 47.142433] systemd[1]: Detected architecture x86-64.1486machine # [ 47.263128] systemd[1]: bpf-restrict-fs: LSM BPF program attached1487machine # [ 47.971953] systemd[1]: Reload requested from client PID 2284 ('.switch-to-conf') (unit backdoor.service)...1488machine # [ 47.974353] systemd[1]: Reloading...1489machine # [ 48.783487] systemd[1]: Reloading finished in 805 ms.1490machine # [ 48.927171] systemd[694]: Reexecution requested from client PID 2387 ('switch-to-confi')...1491machine # [ 48.929763] systemd[694]: Reexecuting.1492machine # [ 48.956543] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1493machine # [ 49.126637] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1494machine # [ 49.132940] systemd[694]: Reload requested from client PID 2387 ('switch-to-confi')...1495machine # [ 49.134204] systemd[694]: Reloading...1496machine # [ 49.309543] systemd[694]: Reloading finished in 173 ms.1497machine # [ 49.314868] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1498machine # [ 49.322131] systemd[694]: Stopped Run user-specific NixOS activation.1499machine # [ 49.323067] systemd[694]: Stopping Run user-specific NixOS activation...1500machine # [ 49.326456] systemd[694]: Starting Run user-specific NixOS activation...1501machine # [ 49.369065] systemd[694]: Finished Run user-specific NixOS activation.1502machine # [ 49.372659] systemd[1]: Stopped target Reactivate sysinit units.1503machine # [ 49.374560] systemd[1]: Stopping Reactivate sysinit units...1504machine # [ 49.375867] systemd[1]: Reached target Reactivate sysinit units.1505machine # [ 49.377693] systemd[1]: Reached target Local File Systems.1506machine # [ 49.380127] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1507machine # [ 49.383943] systemd[1]: Starting Create SUID/SGID Wrappers...1508machine # [ 49.384800] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1509machine # [ 49.385985] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671510machine # [ 49.388114] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1511machine # [ 49.391124] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1512machine # [ 49.393126] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1513machine # [ 49.396314] systemd[1]: Reached target Remote File Systems.1514machine # [ 49.402111] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1515machine # [ 49.403447] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1516machine # [ 49.766485] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1517machine # [ 49.768272] systemd[1]: Finished Create SUID/SGID Wrappers.1518machine # [ 49.774465] systemd[1]: Starting linger-users.service...1519machine # [ 49.829949] systemd[1]: linger-users.service: Deactivated successfully.1520machine # [ 49.831593] systemd[1]: Finished linger-users.service.1521machine # [ 50.089378] nixos[2284]: finished switching to system configuration /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test1522machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 4.49 seconds)1523machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/addedMount/bin/switch-to-configuration test 2>&11524machine # [ 50.837625] nixos[2458]: switching to system configuration /nix/store/4mjk4fwagwybb7b36crb56m8ja7gipx5-nixos-system-machine-test1525machine # [ 50.841254] systemd[1]: Stopped target Remote File Systems.1526machine # [ 50.842206] systemd[1]: Stopped target Local File Systems.1527machine # [ 51.405862] systemd[1]: Reload requested from client PID 2458 ('.switch-to-conf') (unit backdoor.service)...1528machine # [ 51.407550] systemd[1]: Reloading...1529machine # [ 52.022796] systemd[1]: Reloading finished in 612 ms.1530machine # [ 52.171312] systemd[694]: Reexecution requested from client PID 2527 ('switch-to-confi')...1531machine # [ 52.172634] systemd[694]: Reexecuting.1532machine # [ 52.203355] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1533machine # [ 52.372943] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1534machine # [ 52.379085] systemd[694]: Reload requested from client PID 2527 ('switch-to-confi')...1535machine # [ 52.380965] systemd[694]: Reloading...1536machine # [ 52.553983] systemd[694]: Reloading finished in 171 ms.1537machine # [ 52.557845] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1538machine # [ 52.564317] systemd[694]: Stopped Run user-specific NixOS activation.1539machine # [ 52.566060] systemd[694]: Stopping Run user-specific NixOS activation...1540machine # [ 52.568858] systemd[694]: Starting Run user-specific NixOS activation...1541machine # [ 52.610811] systemd[694]: Finished Run user-specific NixOS activation.1542machine # [ 52.614250] systemd[1]: Stopped target Reactivate sysinit units.1543machine # [ 52.615775] systemd[1]: Stopping Reactivate sysinit units...1544machine # [ 52.616784] systemd[1]: Reached target Reactivate sysinit units.1545machine # [ 52.619527] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1546machine # [ 52.621585] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1547machine # [ 52.626785] systemd[1]: Reached target Remote File Systems.1548machine # [ 52.630805] systemd[1]: Starting linger-users.service...1549machine # [ 52.635302] systemd[1]: Mounting /test...1550machine # [ 52.643168] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1551machine # [ 52.647245] systemd[1]: Starting Create SUID/SGID Wrappers...1552machine # [ 52.648897] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671553machine # [ 52.652203] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1554machine # [ 52.653708] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1555machine # [ 52.655035] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1556machine # [ 52.689113] systemd[1]: Mounted /test.1557machine # [ 52.691134] systemd[1]: Reached target Local File Systems.1558machine # [ 52.692539] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1559machine # [ 52.719143] systemd[1]: linger-users.service: Deactivated successfully.1560machine # [ 52.721676] systemd[1]: Finished linger-users.service.1561machine # [ 53.031567] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1562machine # [ 53.034110] systemd[1]: Finished Create SUID/SGID Wrappers.1563machine # [ 53.290829] nixos[2458]: finished switching to system configuration /nix/store/4mjk4fwagwybb7b36crb56m8ja7gipx5-nixos-system-machine-test1564machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/addedMount/bin/switch-to-configuration test 2>&1, in 3.20 seconds)1565machine: must succeed: systemctl stop test.mount1566machine # [ 53.340785] systemd[1]: Stopped target Local File Systems.1567machine # [ 53.345943] systemd[1]: Unmounting /test...1568machine # [ 53.386305] systemd[1]: test.mount: Deactivated successfully.1569machine # [ 53.387928] systemd[1]: Unmounted /test.1570machine: (finished: must succeed: systemctl stop test.mount, in 0.09 seconds)1571machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/addedMount/bin/switch-to-configuration test 2>&11572machine # [ 53.978250] nixos[2606]: switching to system configuration /nix/store/4mjk4fwagwybb7b36crb56m8ja7gipx5-nixos-system-machine-test1573machine # [ 53.980715] systemd[1]: Stopped target Remote File Systems.1574machine # [ 54.399150] systemd[1]: Reload requested from client PID 2606 ('.switch-to-conf') (unit backdoor.service)...1575machine # [ 54.400796] systemd[1]: Reloading...1576machine # [ 54.895152] systemd[1]: Reloading finished in 491 ms.1577machine # [ 55.022593] systemd[694]: Reexecution requested from client PID 2675 ('switch-to-confi')...1578machine # [ 55.023848] systemd[694]: Reexecuting.1579machine # [ 55.054132] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1580machine # [ 55.201714] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1581machine # [ 55.206906] systemd[694]: Reload requested from client PID 2675 ('switch-to-confi')...1582machine # [ 55.208217] systemd[694]: Reloading...1583machine # [ 55.357561] systemd[694]: Reloading finished in 147 ms.1584machine # [ 55.362871] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1585machine # [ 55.369594] systemd[694]: Stopped Run user-specific NixOS activation.1586machine # [ 55.370569] systemd[694]: Stopping Run user-specific NixOS activation...1587machine # [ 55.374405] systemd[694]: Starting Run user-specific NixOS activation...1588machine # [ 55.412828] systemd[694]: Finished Run user-specific NixOS activation.1589machine # [ 55.416488] systemd[1]: Stopped target Reactivate sysinit units.1590machine # [ 55.417936] systemd[1]: Stopping Reactivate sysinit units...1591machine # [ 55.419405] systemd[1]: Reached target Reactivate sysinit units.1592machine # [ 55.427127] systemd[1]: Mounting /test...1593machine # [ 55.427765] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1594machine # [ 55.431236] systemd[1]: Starting Create SUID/SGID Wrappers...1595machine # [ 55.432090] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671596machine # [ 55.434133] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1597machine # [ 55.437150] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1598machine # [ 55.439033] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1599machine # [ 55.444929] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1600machine # [ 55.446190] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1601machine # [ 55.453053] systemd[1]: Reached target Remote File Systems.1602machine # [ 55.483692] systemd[1]: Mounted /test.1603machine # [ 55.484748] systemd[1]: Reached target Local File Systems.1604machine # [ 55.485530] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1605machine # [ 55.761671] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1606machine # [ 55.765238] systemd[1]: Finished Create SUID/SGID Wrappers.1607machine # [ 55.768423] systemd[1]: Starting linger-users.service...1608machine # [ 55.818836] systemd[1]: linger-users.service: Deactivated successfully.1609machine # [ 55.820428] systemd[1]: Finished linger-users.service.1610machine # [ 56.078096] nixos[2606]: finished switching to system configuration /nix/store/4mjk4fwagwybb7b36crb56m8ja7gipx5-nixos-system-machine-test1611machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/addedMount/bin/switch-to-configuration test 2>&1, in 2.69 seconds)1612machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/addedMountOptsModified/bin/switch-to-configuration test 2>&11613machine # [ 56.644627] nixos[2748]: switching to system configuration /nix/store/a53flvp76s3vbbmby7x29zzc189rgbwl-nixos-system-machine-test1614machine # [ 56.646990] systemd[1]: Stopped target Remote File Systems.1615machine # [ 56.648744] systemd[1]: Stopped target Local File Systems.1616machine # [ 57.115487] systemd[1]: Reload requested from client PID 2748 ('.switch-to-conf') (unit backdoor.service)...1617machine # [ 57.117179] systemd[1]: Reloading...1618machine # [ 57.635744] systemd[1]: Reloading finished in 516 ms.1619machine # [ 57.754299] systemd[694]: Reexecution requested from client PID 2817 ('switch-to-confi')...1620machine # [ 57.756420] systemd[694]: Reexecuting.1621machine # [ 57.781077] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1622machine # [ 57.914783] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1623machine # [ 57.920679] systemd[694]: Reload requested from client PID 2817 ('switch-to-confi')...1624machine # [ 57.922221] systemd[694]: Reloading...1625machine # [ 58.058224] systemd[694]: Reloading finished in 134 ms.1626machine # [ 58.062822] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1627machine # [ 58.070228] systemd[694]: Stopped Run user-specific NixOS activation.1628machine # [ 58.071136] systemd[694]: Stopping Run user-specific NixOS activation...1629machine # [ 58.074081] systemd[694]: Starting Run user-specific NixOS activation...1630machine # [ 58.110559] systemd[694]: Finished Run user-specific NixOS activation.1631machine # [ 58.114654] systemd[1]: Stopped target Reactivate sysinit units.1632machine # [ 58.116257] systemd[1]: Stopping Reactivate sysinit units...1633machine # [ 58.117280] systemd[1]: Reached target Reactivate sysinit units.1634machine # [ 58.120457] systemd[1]: Reloading /test...1635machine # [ 58.145223] systemd[1]: Reloaded /test.1636machine # [ 58.150465] systemd[1]: Reached target Remote File Systems.1637machine # [ 58.154522] systemd[1]: Starting linger-users.service...1638machine # [ 58.157076] systemd[1]: Reached target Local File Systems.1639machine # [ 58.159578] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1640machine # [ 58.164982] systemd[1]: Starting Create SUID/SGID Wrappers...1641machine # [ 58.165969] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1642machine # [ 58.167215] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671643machine # [ 58.169184] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1644machine # [ 58.170700] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1645machine # [ 58.171906] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1646machine # [ 58.181164] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1647machine # [ 58.182478] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1648machine # [ 58.234571] systemd[1]: linger-users.service: Deactivated successfully.1649machine # [ 58.236389] systemd[1]: Finished linger-users.service.1650machine # [ 58.467158] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1651machine # [ 58.468810] systemd[1]: Finished Create SUID/SGID Wrappers.1652machine # [ 58.723517] nixos[2748]: finished switching to system configuration /nix/store/a53flvp76s3vbbmby7x29zzc189rgbwl-nixos-system-machine-test1653machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/addedMountOptsModified/bin/switch-to-configuration test 2>&1, in 2.64 seconds)1654machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/addedMountDevModified/bin/switch-to-configuration test 2>&11655machine # [ 59.190833] nixos[2890]: switching to system configuration /nix/store/wr5aj4p7hmd34nwy90rm8mna6kk1czcn-nixos-system-machine-test1656machine # [ 59.193748] systemd[1]: Stopped target Local File Systems.1657machine # [ 59.195385] systemd[1]: Stopped target Remote File Systems.1658machine # [ 59.567473] systemd[1]: Reload requested from client PID 2890 ('.switch-to-conf') (unit backdoor.service)...1659machine # [ 59.569151] systemd[1]: Reloading...1660machine # [ 60.019108] systemd[1]: Reloading finished in 447 ms.1661machine # [ 60.117413] systemd[694]: Reexecution requested from client PID 2959 ('switch-to-confi')...1662machine # [ 60.119929] systemd[694]: Reexecuting.1663machine # [ 60.145228] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1664machine # [ 60.266067] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1665machine # [ 60.272230] systemd[694]: Reload requested from client PID 2959 ('switch-to-confi')...1666machine # [ 60.273489] systemd[694]: Reloading...1667machine # [ 60.397071] systemd[694]: Reloading finished in 120 ms.1668machine # [ 60.402078] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1669machine # [ 60.409062] systemd[694]: Stopped Run user-specific NixOS activation.1670machine # [ 60.410050] systemd[694]: Stopping Run user-specific NixOS activation...1671machine # [ 60.412784] systemd[694]: Starting Run user-specific NixOS activation...1672machine # [ 60.445257] systemd[694]: Finished Run user-specific NixOS activation.1673machine # [ 60.448713] systemd[1]: Stopped target Reactivate sysinit units.1674machine # [ 60.450413] systemd[1]: Stopping Reactivate sysinit units...1675machine # [ 60.451796] systemd[1]: Reached target Reactivate sysinit units.1676machine # [ 60.453979] systemd[1]: Unmounting /test...1677machine # [ 60.477798] systemd[1]: test.mount: Deactivated successfully.1678machine # [ 60.479505] systemd[1]: Unmounted /test.1679machine # [ 60.483182] systemd[1]: Mounting /test...1680machine # [ 60.507710] systemd[1]: Mounted /test.1681machine # [ 60.513703] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1682machine # [ 60.515614] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1683machine # [ 60.521785] systemd[1]: Reached target Local File Systems.1684machine # [ 60.522636] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1685machine # [ 60.526094] systemd[1]: Starting Create SUID/SGID Wrappers...1686machine # [ 60.527469] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1687machine # [ 60.528996] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671688machine # [ 60.532133] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1689machine # [ 60.535159] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1690machine # [ 60.536596] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1691machine # [ 60.539413] systemd[1]: Reached target Remote File Systems.1692machine # [ 60.838109] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1693machine # [ 60.839690] systemd[1]: Finished Create SUID/SGID Wrappers.1694machine # [ 60.844664] systemd[1]: Starting linger-users.service...1695machine # [ 60.893837] systemd[1]: linger-users.service: Deactivated successfully.1696machine # [ 60.895964] systemd[1]: Finished linger-users.service.1697machine # [ 61.149827] nixos[2890]: finished switching to system configuration /nix/store/wr5aj4p7hmd34nwy90rm8mna6kk1czcn-nixos-system-machine-test1698machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/addedMountDevModified/bin/switch-to-configuration test 2>&1, in 2.43 seconds)1699machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/addedMount/bin/switch-to-configuration test 2>&11700machine # [ 61.701714] nixos[3034]: switching to system configuration /nix/store/4mjk4fwagwybb7b36crb56m8ja7gipx5-nixos-system-machine-test1701machine # [ 61.704117] systemd[1]: Stopped target Local File Systems.1702machine # [ 61.705789] systemd[1]: Stopped target Remote File Systems.1703machine # [ 62.090541] systemd[1]: Reload requested from client PID 3034 ('.switch-to-conf') (unit backdoor.service)...1704machine # [ 62.092242] systemd[1]: Reloading...1705machine # [ 62.574707] systemd[1]: Reloading finished in 479 ms.1706machine # [ 62.687109] systemd[694]: Reexecution requested from client PID 3103 ('switch-to-confi')...1707machine # [ 62.689807] systemd[694]: Reexecuting.1708machine # [ 62.715633] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1709machine # [ 62.850905] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1710machine # [ 62.856119] systemd[694]: Reload requested from client PID 3103 ('switch-to-confi')...1711machine # [ 62.857661] systemd[694]: Reloading...1712machine # [ 62.991458] systemd[694]: Reloading finished in 132 ms.1713machine # [ 62.996844] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1714machine # [ 63.003255] systemd[694]: Stopped Run user-specific NixOS activation.1715machine # [ 63.005064] systemd[694]: Stopping Run user-specific NixOS activation...1716machine # [ 63.007194] systemd[694]: Starting Run user-specific NixOS activation...1717machine # [ 63.037827] systemd[694]: Finished Run user-specific NixOS activation.1718machine # [ 63.041327] systemd[1]: Stopped target Reactivate sysinit units.1719machine # [ 63.043085] systemd[1]: Stopping Reactivate sysinit units...1720machine # [ 63.043908] systemd[1]: Reached target Reactivate sysinit units.1721machine # [ 63.046319] systemd[1]: Unmounting /test...1722machine # [ 63.073793] systemd[1]: test.mount: Deactivated successfully.1723machine # [ 63.076193] systemd[1]: Unmounted /test.1724machine # [ 63.079247] systemd[1]: Mounting /test...1725machine # [ 63.109386] systemd[1]: Mounted /test.1726machine # [ 63.113430] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1727machine # [ 63.115121] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1728machine # [ 63.117914] systemd[1]: Reached target Remote File Systems.1729machine # [ 63.121376] systemd[1]: Starting linger-users.service...1730machine # [ 63.124025] systemd[1]: Reached target Local File Systems.1731machine # [ 63.126527] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1732machine # [ 63.133063] systemd[1]: Starting Create SUID/SGID Wrappers...1733machine # [ 63.133878] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1734machine # [ 63.135544] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671735machine # [ 63.137497] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1736machine # [ 63.139781] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1737machine # [ 63.142209] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1738machine # [ 63.193168] systemd[1]: linger-users.service: Deactivated successfully.1739machine # [ 63.195068] systemd[1]: Finished linger-users.service.1740machine # [ 63.425190] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1741machine # [ 63.426978] systemd[1]: Finished Create SUID/SGID Wrappers.1742machine # [ 63.682122] nixos[3034]: finished switching to system configuration /nix/store/4mjk4fwagwybb7b36crb56m8ja7gipx5-nixos-system-machine-test1743machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/addedMount/bin/switch-to-configuration test 2>&1, in 2.53 seconds)1744machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/bin/switch-to-configuration test 2>&11745machine # [ 64.162150] nixos[3178]: switching to system configuration /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test1746machine # [ 64.165269] systemd[1]: Stopped target Local File Systems.1747machine # [ 64.167788] systemd[1]: Stopped target Remote File Systems.1748machine # [ 64.171065] systemd[1]: Unmounting /test...1749machine # [ 64.199641] systemd[1]: test.mount: Deactivated successfully.1750machine # [ 64.200985] systemd[1]: Unmounted /test.1751machine # [ 64.551912] systemd[1]: Reload requested from client PID 3178 ('.switch-to-conf') (unit backdoor.service)...1752machine # [ 64.553551] systemd[1]: Reloading...1753machine # [ 65.031948] systemd[1]: Reloading finished in 475 ms.1754machine # [ 65.151177] systemd[694]: Reexecution requested from client PID 3249 ('switch-to-confi')...1755machine # [ 65.153810] systemd[694]: Reexecuting.1756machine # [ 65.179645] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1757machine # [ 65.323213] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1758machine # [ 65.329274] systemd[694]: Reload requested from client PID 3249 ('switch-to-confi')...1759machine # [ 65.330506] systemd[694]: Reloading...1760machine # [ 65.477891] systemd[694]: Reloading finished in 145 ms.1761machine # [ 65.482816] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1762machine # [ 65.490063] systemd[694]: Stopped Run user-specific NixOS activation.1763machine # [ 65.490918] systemd[694]: Stopping Run user-specific NixOS activation...1764machine # [ 65.493964] systemd[694]: Starting Run user-specific NixOS activation...1765machine # [ 65.534908] systemd[694]: Finished Run user-specific NixOS activation.1766machine # [ 65.538348] systemd[1]: Stopped target Reactivate sysinit units.1767machine # [ 65.539838] systemd[1]: Stopping Reactivate sysinit units...1768machine # [ 65.540901] systemd[1]: Reached target Reactivate sysinit units.1769machine # [ 65.543643] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1770machine # [ 65.545541] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1771machine # [ 65.550067] systemd[1]: Reached target Remote File Systems.1772machine # [ 65.553863] systemd[1]: Starting linger-users.service...1773machine # [ 65.555572] systemd[1]: Reached target Local File Systems.1774machine # [ 65.562109] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1775machine # [ 65.565325] systemd[1]: Starting Create SUID/SGID Wrappers...1776machine # [ 65.567121] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1777machine # [ 65.569172] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671778machine # [ 65.571186] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1779machine # [ 65.572625] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1780machine # [ 65.573787] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1781machine # [ 65.631627] systemd[1]: linger-users.service: Deactivated successfully.1782machine # [ 65.633450] systemd[1]: Finished linger-users.service.1783machine # [ 65.898856] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1784machine # [ 65.901086] systemd[1]: Finished Create SUID/SGID Wrappers.1785machine # [ 66.157705] nixos[3178]: finished switching to system configuration /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test1786machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.48 seconds)1787machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/storeMountModified/bin/switch-to-configuration test 2>&11788machine # [ 66.744126] nixos[3320]: switching to system configuration /nix/store/qxld30kmpfv3q1y6h6mman6nq13mz7jd-nixos-system-machine-test1789machine # [ 66.746597] systemd[1]: Stopped target Local File Systems.1790machine # [ 66.748268] systemd[1]: Stopped target Remote File Systems.1791machine # [ 67.303941] systemd[1]: Reload requested from client PID 3320 ('.switch-to-conf') (unit backdoor.service)...1792machine # [ 67.306544] systemd[1]: Reloading...1793machine # [ 68.042900] systemd[1]: Reloading finished in 732 ms.1794machine # [ 68.196082] systemd[694]: Reexecution requested from client PID 3389 ('switch-to-confi')...1795machine # [ 68.199961] systemd[694]: Reexecuting.1796machine # [ 68.236049] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1797machine # [ 68.464246] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1798machine # [ 68.472790] systemd[694]: Reload requested from client PID 3389 ('switch-to-confi')...1799machine # [ 68.474845] systemd[694]: Reloading...1800machine # [ 68.708905] systemd[694]: Reloading finished in 231 ms.1801machine # [ 68.714923] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1802machine # [ 68.724130] systemd[694]: Stopped Run user-specific NixOS activation.1803machine # [ 68.725394] systemd[694]: Stopping Run user-specific NixOS activation...1804machine # [ 68.729120] systemd[694]: Starting Run user-specific NixOS activation...1805machine # [ 68.782300] systemd[694]: Finished Run user-specific NixOS activation.1806machine # [ 68.786700] systemd[1]: Stopped target Reactivate sysinit units.1807machine # [ 68.788616] systemd[1]: Stopping Reactivate sysinit units...1808machine # [ 68.790219] systemd[1]: Reached target Reactivate sysinit units.1809machine # [ 68.796339] systemd[1]: Reached target Local File Systems.1810machine # [ 68.799617] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1811machine # [ 68.802210] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1812machine # [ 68.807075] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1813machine # [ 68.813182] systemd[1]: Starting Create SUID/SGID Wrappers...1814machine # [ 68.814323] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1815machine # [ 68.816927] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671816machine # [ 68.820972] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1817machine # [ 68.824707] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1818machine # [ 68.826547] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1819machine # [ 68.829160] systemd[1]: Reached target Remote File Systems.1820machine # [ 69.132164] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1821machine # [ 69.133447] systemd[1]: Finished Create SUID/SGID Wrappers.1822machine # [ 69.137727] systemd[1]: Starting linger-users.service...1823machine # [ 69.181312] systemd[1]: linger-users.service: Deactivated successfully.1824machine # [ 69.182788] systemd[1]: Finished linger-users.service.1825machine # [ 69.437644] nixos[3320]: finished switching to system configuration /nix/store/qxld30kmpfv3q1y6h6mman6nq13mz7jd-nixos-system-machine-test1826machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/storeMountModified/bin/switch-to-configuration test 2>&1, in 3.28 seconds)1827machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/automount/bin/switch-to-configuration test 2>&11828machine # [ 70.002896] nixos[3460]: switching to system configuration /nix/store/zldkarzj79cq7sxq6qig7qra7nf2mhyj-nixos-system-machine-test1829machine # [ 70.006063] systemd[1]: Stopped target Remote File Systems.1830machine # [ 70.007820] systemd[1]: Stopped target Local File Systems.1831machine # [ 70.466878] systemd[1]: Reload requested from client PID 3460 ('.switch-to-conf') (unit backdoor.service)...1832machine # [ 70.468587] systemd[1]: Reloading...1833machine # [ 71.025466] systemd[1]: Reloading finished in 553 ms.1834machine # [ 71.144841] systemd[694]: Reexecution requested from client PID 3529 ('switch-to-confi')...1835machine # [ 71.146174] systemd[694]: Reexecuting.1836machine # [ 71.174691] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1837machine # [ 71.319261] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1838machine # [ 71.324305] systemd[694]: Reload requested from client PID 3529 ('switch-to-confi')...1839machine # [ 71.326160] systemd[694]: Reloading...1840machine # [ 71.471182] systemd[694]: Reloading finished in 143 ms.1841machine # [ 71.476896] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1842machine # [ 71.483554] systemd[694]: Stopped Run user-specific NixOS activation.1843machine # [ 71.484564] systemd[694]: Stopping Run user-specific NixOS activation...1844machine # [ 71.487879] systemd[694]: Starting Run user-specific NixOS activation...1845machine # [ 71.523931] systemd[694]: Finished Run user-specific NixOS activation.1846machine # [ 71.527416] systemd[1]: Stopped target Reactivate sysinit units.1847machine # [ 71.528814] systemd[1]: Stopping Reactivate sysinit units...1848machine # [ 71.529907] systemd[1]: Reached target Reactivate sysinit units.1849machine # [ 71.531838] systemd[1]: Reached target Remote File Systems.1850machine # [ 71.535723] systemd[1]: Starting linger-users.service...1851machine # [ 71.541070] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1852machine # [ 71.542274] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1853machine # [ 71.548059] systemd[1]: Set up automount testauto.automount.1854machine # [ 71.548857] systemd[1]: Reached target Local File Systems.1855machine # [ 71.552939] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1856machine # [ 71.557817] systemd[1]: Starting Create SUID/SGID Wrappers...1857machine # [ 71.558616] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1858machine # [ 71.559747] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671859machine # [ 71.561670] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1860machine # [ 71.564623] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1861machine # [ 71.568330] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1862machine # [ 71.615430] systemd[1]: linger-users.service: Deactivated successfully.1863machine # [ 71.617266] systemd[1]: Finished linger-users.service.1864machine # [ 71.862320] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1865machine # [ 71.864085] systemd[1]: Finished Create SUID/SGID Wrappers.1866machine # [ 72.121978] nixos[3460]: finished switching to system configuration /nix/store/zldkarzj79cq7sxq6qig7qra7nf2mhyj-nixos-system-machine-test1867machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/automount/bin/switch-to-configuration test 2>&1, in 2.69 seconds)1868machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/bin/switch-to-configuration test 2>&11869machine # [ 72.676601] nixos[3600]: switching to system configuration /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test1870machine # [ 72.679219] systemd[1]: Stopped target Local File Systems.1871machine # [ 72.681589] systemd[1]: testauto.automount: Deactivated successfully.1872machine # [ 72.683262] systemd[1]: Unset automount testauto.automount.1873machine # [ 72.685092] systemd[1]: Stopped target Remote File Systems.1874machine # [ 73.042348] systemd[1]: Reload requested from client PID 3600 ('.switch-to-conf') (unit backdoor.service)...1875machine # [ 73.044148] systemd[1]: Reloading...1876machine # [ 73.566856] systemd[1]: Reloading finished in 520 ms.1877machine # [ 73.662300] systemd[694]: Reexecution requested from client PID 3669 ('switch-to-confi')...1878machine # [ 73.664329] systemd[694]: Reexecuting.1879machine # [ 73.692302] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1880machine # [ 73.859385] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1881machine # [ 73.864562] systemd[694]: Reload requested from client PID 3669 ('switch-to-confi')...1882machine # [ 73.866253] systemd[694]: Reloading...1883machine # [ 74.027072] systemd[694]: Reloading finished in 158 ms.1884machine # [ 74.030815] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1885machine # [ 74.037260] systemd[694]: Stopped Run user-specific NixOS activation.1886machine # [ 74.038620] systemd[694]: Stopping Run user-specific NixOS activation...1887machine # [ 74.042801] systemd[694]: Starting Run user-specific NixOS activation...1888machine # [ 74.078966] systemd[694]: Finished Run user-specific NixOS activation.1889machine # [ 74.082326] systemd[1]: Stopped target Reactivate sysinit units.1890machine # [ 74.083741] systemd[1]: Stopping Reactivate sysinit units...1891machine # [ 74.084710] systemd[1]: Reached target Reactivate sysinit units.1892machine # [ 74.088732] systemd[1]: Reached target Remote File Systems.1893machine # [ 74.094089] systemd[1]: Starting linger-users.service...1894machine # [ 74.096851] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1895machine # [ 74.098224] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1896machine # [ 74.101769] systemd[1]: Reached target Local File Systems.1897machine # [ 74.102770] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1898machine # [ 74.107305] systemd[1]: Starting Create SUID/SGID Wrappers...1899machine # [ 74.108191] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1900machine # [ 74.109336] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671901machine # [ 74.112293] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1902machine # [ 74.114709] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1903machine # [ 74.116160] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1904machine # [ 74.171656] systemd[1]: linger-users.service: Deactivated successfully.1905machine # [ 74.173330] systemd[1]: Finished linger-users.service.1906machine # [ 74.426663] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1907machine # [ 74.429749] systemd[1]: Finished Create SUID/SGID Wrappers.1908machine # [ 74.684401] nixos[3600]: finished switching to system configuration /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test1909machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.56 seconds)1910(finished: subtest: fstab mounts, in 29.08 seconds)1911subtest: swaps1912machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/swap/bin/switch-to-configuration test 2>&11913machine # [ 75.285956] nixos[3740]: switching to system configuration /nix/store/fngqfjyq7kxwrplgicqdpxc2p998vlim-nixos-system-machine-test1914machine # [ 75.288370] systemd[1]: Stopped target Local File Systems.1915machine # [ 75.290162] systemd[1]: Stopped target Remote File Systems.1916machine # [ 75.735679] systemd[1]: Reload requested from client PID 3740 ('.switch-to-conf') (unit backdoor.service)...1917machine # [ 75.737354] systemd[1]: Reloading...1918machine # [ 76.288125] systemd[1]: Reloading finished in 548 ms.1919machine # [ 76.405078] systemd[694]: Reexecution requested from client PID 3809 ('switch-to-confi')...1920machine # [ 76.406398] systemd[694]: Reexecuting.1921machine # [ 76.434073] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1922machine # [ 76.579315] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1923machine # [ 76.584866] systemd[694]: Reload requested from client PID 3809 ('switch-to-confi')...1924machine # [ 76.586102] systemd[694]: Reloading...1925machine # [ 76.730299] systemd[694]: Reloading finished in 142 ms.1926machine # [ 76.735821] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1927machine # [ 76.743073] systemd[694]: Stopped Run user-specific NixOS activation.1928machine # [ 76.743963] systemd[694]: Stopping Run user-specific NixOS activation...1929machine # [ 76.747068] systemd[694]: Starting Run user-specific NixOS activation...1930machine # [ 76.783332] systemd[694]: Finished Run user-specific NixOS activation.1931machine # [ 76.786812] systemd[1]: Stopped target Reactivate sysinit units.1932machine # [ 76.788285] systemd[1]: Stopping Reactivate sysinit units...1933machine # [ 76.790101] systemd[1]: Reached target Reactivate sysinit units.1934machine # [ 76.790913] systemd[1]: Reached target Remote File Systems.1935machine # [ 76.794735] systemd[1]: Starting Initialisation of swap device /swapfile...1936machine # [ 76.797064] systemd[1]: Reached target Local File Systems.1937machine # [ 76.802936] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1938machine # [ 76.805498] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1939machine # [ 76.810374] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1940machine # [ 76.815561] systemd[1]: Starting Create SUID/SGID Wrappers...1941machine # [ 76.816551] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1942machine # [ 76.817676] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671943machine # [ 76.819615] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1944machine # [ 76.821983] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1945machine # [ 76.825164] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1946machine # [ 76.895212] mkswap-swapfile-start[3821]: Creating swap file using dd and mkswap.1947machine # [ 76.923950] mkswap-swapfile-start[3840]: 1+0 records in1948machine # [ 76.924892] mkswap-swapfile-start[3840]: 1+0 records out1949machine # [ 76.926084] mkswap-swapfile-start[3840]: 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.00294535 s, 356 MB/s1950machine # [ 76.945362] mkswap-swapfile-start[3844]: Setting up swapspace version 1, size = 1020 KiB (1044480 bytes)1951machine # [ 76.946694] mkswap-swapfile-start[3844]: no label, UUID=51a12828-1370-4f3b-a85b-e3cb14f3d8821952machine # [ 76.949855] systemd[1]: mkswap-swapfile.service: Deactivated successfully.1953machine # [ 76.951539] systemd[1]: Finished Initialisation of swap device /swapfile.1954machine # [ 76.953147] systemd[1]: mkswap-swapfile.service: Consumed 29ms CPU time over 156ms wall clock time, 3.3M memory peak, 1M written to disk.1955machine # [ 76.958372] systemd[1]: Activating swap /swapfile...1956machine # [ 77.139315] Adding 1020k swap on /swapfile. Priority:-2 extents:1 across:1020k1957machine # [ 76.997764] systemd[1]: Activated swap /swapfile.1958machine # [ 77.164143] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1959machine # [ 77.166426] systemd[1]: Finished Create SUID/SGID Wrappers.1960machine # [ 77.169745] systemd[1]: Starting linger-users.service...1961machine # [ 77.221720] systemd[1]: linger-users.service: Deactivated successfully.1962machine # [ 77.223655] systemd[1]: Finished linger-users.service.1963machine # [ 77.479825] nixos[3740]: finished switching to system configuration /nix/store/fngqfjyq7kxwrplgicqdpxc2p998vlim-nixos-system-machine-test1964machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/specialisation/swap/bin/switch-to-configuration test 2>&1, in 2.80 seconds)1965machine: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/bin/switch-to-configuration test 2>&11966machine # [ 78.078625] systemd[1]: swapfile.swap: Deactivated successfully.1967machine # [ 78.080251] nixos[3894]: switching to system configuration /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test1968machine # [ 78.082807] systemd[1]: Stopped target Remote File Systems.1969machine # [ 78.084146] systemd[1]: Stopped target Local File Systems.1970machine # [ 78.504564] systemd[1]: Reload requested from client PID 3894 ('.switch-to-conf') (unit backdoor.service)...1971machine # [ 78.506161] systemd[1]: Reloading...1972machine # [ 79.026949] systemd[1]: Reloading finished in 518 ms.1973machine # [ 79.145327] systemd[694]: Reexecution requested from client PID 3963 ('switch-to-confi')...1974machine # [ 79.146636] systemd[694]: Reexecuting.1975machine # [ 79.173790] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1976machine # [ 79.323166] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1977machine # [ 79.328762] systemd[694]: Reload requested from client PID 3963 ('switch-to-confi')...1978machine # [ 79.330002] systemd[694]: Reloading...1979machine # [ 79.479526] systemd[694]: Reloading finished in 146 ms.1980machine # [ 79.482815] systemd[694]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1981machine # [ 79.489277] systemd[694]: Stopped Run user-specific NixOS activation.1982machine # [ 79.491059] systemd[694]: Stopping Run user-specific NixOS activation...1983machine # [ 79.493463] systemd[694]: Starting Run user-specific NixOS activation...1984machine # [ 79.530967] systemd[694]: Finished Run user-specific NixOS activation.1985machine # [ 79.534931] systemd[1]: Stopped target Reactivate sysinit units.1986machine # [ 79.535941] systemd[1]: Stopping Reactivate sysinit units...1987machine # [ 79.537164] systemd[1]: Reached target Reactivate sysinit units.1988machine # [ 79.538615] systemd[1]: Reached target Local File Systems.1989machine # [ 79.541078] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1990machine # [ 79.546122] systemd[1]: Starting Create SUID/SGID Wrappers...1991machine # [ 79.547030] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1992machine # [ 79.548198] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671993machine # [ 79.551707] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1994machine # [ 79.554179] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1995machine # [ 79.556115] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1996machine # [ 79.558589] systemd[1]: Reached target Remote File Systems.1997machine # [ 79.563553] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1998machine # [ 79.564767] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1999machine # [ 79.855833] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2000machine # [ 79.857513] systemd[1]: Finished Create SUID/SGID Wrappers.2001machine # [ 79.862214] systemd[1]: Starting linger-users.service...2002machine # [ 79.905299] systemd[1]: linger-users.service: Deactivated successfully.2003machine # [ 79.906765] systemd[1]: Finished linger-users.service.2004machine # [ 80.161068] nixos[3894]: finished switching to system configuration /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test2005machine: (finished: must succeed: /nix/store/wmg1nnzsv1d0vrfdgrqyc833nshgf6y1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.68 seconds)2006(finished: subtest: swaps, in 5.47 seconds)2007(finished: run the VM test script, in 81.10 seconds)2008test script finished in 81.14s2009cleanup2010kill QemuMachine (pid 45)2011machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/b5bpi6zfajzzrwwpgba2q6li3nnya4bs-python3-3.14.7/bin/python3.14)2012(finished: cleanup, in 0.13 seconds)2013additionally exposed symbols:2014 machine, other,2015 vlan1,2016 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_ssh