vm-test-run-switch-test-basics
checks.x86_64-linux.switchTest-basics
· build #52
· 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: QEMU running (pid 45)11machine # Disk image does not exist, creating the virtualisation disk image...12machine # Formatting '/build/vm-state-machine/tmp.eh3CBHTB56', 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: f4593f0d-0a32-4ebf-896c-1d0acd2ea95717machine # 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 # Starting virtiofs daemons...27machine # [2026-10-03T03:32:26Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28machine # [2026-10-03T03:32:26Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29machine # [2026-10-03T03:32:26Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)30machine # [2026-10-03T03:32:26Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether31machine # [2026-10-03T03:32:26Z INFO virtiofsd] Waiting for vhost-user socket connection...32machine # [2026-10-03T03:32:26Z INFO virtiofsd] Waiting for vhost-user socket connection...33machine # [2026-10-03T03:32:26Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)34machine # [2026-10-03T03:32:26Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether35machine # [2026-10-03T03:32:26Z INFO virtiofsd] Client connected, servicing requests36machine # [2026-10-03T03:32:26Z INFO virtiofsd] Client connected, servicing requests37machine # [2026-10-03T03:32:26Z INFO virtiofsd] Waiting for vhost-user socket connection...38machine # [2026-10-03T03:32:26Z INFO virtiofsd] Client connected, servicing requests39machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)40machine # 41machine # 42machine # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0043machine # Press Ctrl-B to configure iPXE (PCI 00:02.0)...44machine # 45machine # 46machine # 47machine # 48machine # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0049machine # Press Ctrl-B to configure iPXE (PCI 00:05.0)...50machine # 51machine # 52machine # Booting from ROM...53machine # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 25 14:35:54 UTC 202654machine # [ 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/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/init regInfo=/nix/.ro-store/h08p5hmslkpiakf2hw59wmsykfp3apg0-closure-info/registration console=ttyS0,115200n8 console=tty055machine # [ 0.000000] BIOS-provided physical RAM map:56machine # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable57machine # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved58machine # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved59machine # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable60machine # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved61machine # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved62machine # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved63machine # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved64machine # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved65machine # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved66machine # [ 0.000000] NX (Execute Disable) protection: active67machine # [ 0.000000] APIC: Static calls initialized68machine # [ 0.000000] SMBIOS 2.8 present.69machine # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/201470machine # [ 0.000000] DMI: Memory slots populated: 1/171machine # [ 0.000000] Hypervisor detected: KVM72machine # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000073machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d0074machine # [ 0.000000] kvm-clock: using sched offset of 498147641 cycles75machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns76machine # [ 0.000005] tsc: Detected 2400.012 MHz processor77machine # [ 0.000756] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000078machine # [ 0.000783] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs79machine # [ 0.000786] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT80machine # [ 0.002680] found SMP MP-table at [mem 0x000f5450-0x000f545f]81machine # [ 0.002690] Using GB pages for direct mapping82machine # [ 0.002801] RAMDISK: [mem 0x3e34b000-0x3ffcffff]83machine # [ 0.002810] ACPI: Early table checksum verification disabled84machine # [ 0.002814] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )85machine # [ 0.002818] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)86machine # [ 0.002822] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)87machine # [ 0.002829] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)88machine # [ 0.002832] ACPI: FACS 0x000000003FFE0000 00004089machine # [ 0.002833] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)90machine # [ 0.002835] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)91machine # [ 0.002836] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)92machine # [ 0.002838] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)93machine # [ 0.002839] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]94machine # [ 0.002840] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]95machine # [ 0.002841] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]96machine # [ 0.002841] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]97machine # [ 0.002842] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]98machine # [ 0.002842] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]99machine # [ 0.002843] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]100machine # [ 0.003072] No NUMA configuration found101machine # [ 0.003073] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]102machine # [ 0.003075] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]103machine # [ 0.005532] Zone ranges:104machine # [ 0.005533] DMA [mem 0x0000000000001000-0x0000000000ffffff]105machine # [ 0.005535] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]106machine # [ 0.005536] Normal empty107machine # [ 0.005537] Device empty108machine # [ 0.005537] Movable zone start for each node109machine # [ 0.005538] Early memory node ranges110machine # [ 0.005539] node 0: [mem 0x0000000000001000-0x000000000009efff]111machine # [ 0.005539] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]112machine # [ 0.005541] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]113machine # [ 0.005561] On node 0, zone DMA: 1 pages in unavailable ranges114machine # [ 0.005853] On node 0, zone DMA: 97 pages in unavailable ranges115machine # [ 0.025068] On node 0, zone DMA32: 40 pages in unavailable ranges116machine # [ 0.025515] ACPI: PM-Timer IO Port: 0x608117machine # [ 0.025525] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])118machine # [ 0.025553] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23119machine # [ 0.025556] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)120machine # [ 0.025557] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)121machine # [ 0.025558] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)122machine # [ 0.025559] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)123machine # [ 0.025560] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)124machine # [ 0.025562] ACPI: Using ACPI (MADT) for SMP configuration information125machine # [ 0.025564] ACPI: HPET id: 0x8086a201 base: 0xfed00000126machine # [ 0.025566] TSC deadline timer available127machine # [ 0.025571] CPU topo: Max. logical packages: 1128machine # [ 0.025572] CPU topo: Max. logical dies: 1129machine # [ 0.025572] CPU topo: Max. dies per package: 1130machine # [ 0.025575] CPU topo: Max. threads per core: 1131machine # [ 0.025576] CPU topo: Num. cores per package: 1132machine # [ 0.025576] CPU topo: Num. threads per package: 1133machine # [ 0.025576] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs134machine # [ 0.025593] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()135machine # [ 0.025623] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]136machine # [ 0.025624] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]137machine # [ 0.025626] [mem 0x40000000-0xafffffff] available for PCI devices138machine # [ 0.025627] Booting paravirtualized kernel on KVM139machine # [ 0.025630] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns140machine # [ 0.030257] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1141machine # [ 0.032571] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152142machine # [ 0.032615] kvm-guest: PV spinlocks disabled, single CPU143machine # [ 0.032617] 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/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/init regInfo=/nix/.ro-store/h08p5hmslkpiakf2hw59wmsykfp3apg0-closure-info/registration console=ttyS0,115200n8 console=tty0144machine # [ 0.032712] Unknown kernel command line parameters "regInfo=/nix/.ro-store/h08p5hmslkpiakf2hw59wmsykfp3apg0-closure-info/registration", will be passed to user space.145machine # [ 0.032724] random: crng init done146machine # [ 0.032725] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes147machine # [ 0.033868] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)148machine # [ 0.033881] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)149machine # [ 0.033911] Fallback order for Node 0: 0150machine # [ 0.033913] Built 1 zonelists, mobility grouping on. Total pages: 262006151machine # [ 0.033914] Policy zone: DMA32152machine # [ 0.036674] mem auto-init: stack:all(zero), heap alloc:on, heap free:off153machine # [ 0.039292] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1154machine # [ 0.041707] allocated 2097152 bytes of page_ext155machine # [ 0.051736] ftrace: allocating 48820 entries in 192 pages156machine # [ 0.051738] ftrace: allocated 192 pages with 2 groups157machine # [ 0.052623] Dynamic Preempt: lazy158machine # [ 0.052740] rcu: Preemptible hierarchical RCU implementation.159machine # [ 0.052741] rcu: RCU event tracing is enabled.160machine # [ 0.052742] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.161machine # [ 0.052743] Trampoline variant of Tasks RCU enabled.162machine # [ 0.052744] Rude variant of Tasks RCU enabled.163machine # [ 0.052744] Tracing variant of Tasks RCU enabled.164machine # [ 0.052745] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.165machine # [ 0.052746] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1166machine # [ 0.052760] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.167machine # [ 0.052762] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.168machine # [ 0.052763] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.169machine # [ 0.057171] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16170machine # [ 0.057500] rcu: srcu_init: Setting srcu_struct sizes based on contention.171machine # [ 0.057509] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns172machine # [ 0.057612] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)173machine # [ 0.061138] Console: colour VGA+ 80x25174machine # [ 0.061141] printk: legacy console [tty0] enabled175machine # [ 0.090691] printk: legacy console [ttyS0] enabled176machine # [ 0.196189] ACPI: Core revision 20250807177machine # [ 0.197135] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns178machine # [ 0.198726] APIC: Switch to symmetric I/O mode setup179machine # [ 0.199765] x2apic enabled180machine # [ 0.200514] APIC: Switched APIC routing to: physical x2apic181machine # [ 0.202387] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1182machine # [ 0.203425] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns183machine # [ 0.205188] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012)184machine # [ 0.206273] x86/cpu: User Mode Instruction Prevention (UMIP) activated185machine # [ 0.208312] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127186machine # [ 0.209184] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0187machine # [ 0.210188] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto188machine # [ 0.211184] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl189machine # [ 0.212184] Transient Scheduler Attacks: Vulnerable: No microcode190machine # [ 0.213184] Spectre V2 : Mitigation: Enhanced / Automatic IBRS191machine # [ 0.214184] Speculative Return Stack Overflow: Mitigation: Safe RET192machine # [ 0.215184] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization193machine # [ 0.217192] Spectre V2 : Enabling IBPB for BPF194machine # [ 0.218185] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier195machine # [ 0.219185] active return thunk: srso_alias_return_thunk196machine # [ 0.220904] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'197machine # [ 0.222184] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'198machine # [ 0.223184] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'199machine # [ 0.224184] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'200machine # [ 0.225184] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'201machine # [ 0.226184] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'202machine # [ 0.227184] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'203machine # [ 0.228184] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256204machine # [ 0.229957] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64205machine # [ 0.230963] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512206machine # [ 0.231965] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024207machine # [ 0.232926] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8208machine # [ 0.233965] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.209machine # [ 0.268758] Freeing SMP alternatives memory: 48K210machine # [ 0.269188] pid_max: default: 32768 minimum: 301211machine # [ 0.270282] LSM: initializing lsm=capability,landlock,yama,bpf,ima212machine # [ 0.271290] landlock: Up and running.213machine # [ 0.272184] Yama: becoming mindful.214machine # [ 0.273209] LSM support for eBPF active215machine # [ 0.273960] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)216machine # [ 0.275217] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)217machine # [ 0.277699] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)218machine # [ 0.278777] Performance Events: Fam17h+ core perfctr, AMD PMU driver.219machine # [ 0.279188] ... version: 2220machine # [ 0.279940] ... bit width: 48221machine # [ 0.280186] ... generic counters: 6222machine # [ 0.280952] ... generic bitmap: 000000000000003f223machine # [ 0.281193] ... fixed-purpose counters: 0224machine # [ 0.281920] ... fixed-purpose bitmap: 0000000000000000225machine # [ 0.282186] ... value mask: 0000ffffffffffff226machine # [ 0.283131] ... max period: 00007fffffffffff227machine # [ 0.283931] ... global_ctrl mask: 000000000000003f228machine # [ 0.284283] signal: max sigframe size: 3376229machine # [ 0.285145] rcu: Hierarchical SRCU implementation.230machine # [ 0.285847] rcu: Max phase no-delay instances is 400.231machine # [ 0.290807] smp: Bringing up secondary CPUs ...232machine # [ 0.291200] smp: Brought up 1 node, 1 CPU233machine # [ 0.291894] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)234machine # [ 0.292356] Memory: 940944K/1048024K available (18120K kernel code, 2728K rwdata, 13640K rodata, 3656K init, 3632K bss, 99724K reserved, 0K cma-reserved)235machine # [ 0.293443] devtmpfs: initialized236machine # [ 0.294231] x86/mm: Memory block size: 128MB237machine # [ 0.295983] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)238machine # [ 0.297129] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).239machine # [ 0.298283] pinctrl core: initialized pinctrl subsystem240machine # [ 0.299435] PM: RTC time: 03:32:26, date: 2026-10-03241machine # [ 0.303473] NET: Registered PF_NETLINK/PF_ROUTE protocol family242machine # [ 0.304574] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations243machine # [ 0.305208] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations244machine # [ 0.306343] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations245machine # [ 0.307197] audit: initializing netlink subsys (disabled)246machine # [ 0.308409] thermal_sys: Registered thermal governor 'fair_share'247machine # [ 0.308411] thermal_sys: Registered thermal governor 'bang_bang'248machine # [ 0.309188] thermal_sys: Registered thermal governor 'step_wise'249machine # [ 0.310189] audit: type=2000 audit(1790998347.165:1): state=initialized audit_enabled=0 res=1250machine # [ 0.312188] thermal_sys: Registered thermal governor 'user_space'251machine # [ 0.312189] thermal_sys: Registered thermal governor 'power_allocator'252machine # [ 0.313201] cpuidle: using governor menu253machine # [ 0.315441] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5254machine # [ 0.316518] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]255machine # [ 0.317190] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry256machine # [ 0.318200] PCI: Using configuration type 1 for base access257machine # [ 0.319354] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.258machine # [ 0.326354] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages259machine # [ 0.327187] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page260machine # [ 0.332187] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages261machine # [ 0.333187] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page262machine # [ 0.343658] ACPI: Added _OSI(Module Device)263machine # [ 0.344188] ACPI: Added _OSI(Processor Device)264machine # [ 0.344940] ACPI: Added _OSI(Processor Aggregator Device)265machine # [ 0.351832] ACPI: 1 ACPI AML tables successfully acquired and loaded266machine # [ 0.355620] ACPI: Interpreter enabled267machine # [ 0.356202] ACPI: PM: (supports S0 S3 S4 S5)268machine # [ 0.356954] ACPI: Using IOAPIC for interrupt routing269machine # [ 0.359248] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug270machine # [ 0.360187] PCI: Using E820 reservations for host bridge windows271machine # [ 0.363315] ACPI: Enabled 2 GPEs in block 00 to 3F272machine # [ 0.369061] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])273machine # [ 0.370116] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]274machine # [ 0.371275] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]275machine # [ 0.372310] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]276machine # [ 0.373524] PCI host bridge to bus 0000:00277machine # [ 0.374191] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]278machine # [ 0.375186] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]279machine # [ 0.376187] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]280machine # [ 0.377187] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]281machine # [ 0.378186] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]282machine # [ 0.379187] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]283machine # [ 0.380187] pci_bus 0000:00: root bus resource [bus 00-ff]284machine # [ 0.381216] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint285machine # [ 0.382654] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint286machine # [ 0.385266] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]287machine # [ 0.386243] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]288machine # [ 0.387209] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]289machine # [ 0.388517] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]290machine # [ 0.389919] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint291machine # [ 0.392220] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]292machine # [ 0.393193] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]293machine # [ 0.394208] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]294machine # [ 0.395193] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]295machine # [ 0.396729] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint296machine # [ 0.398222] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]297machine # [ 0.399127] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]298machine # [ 0.400052] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]299machine # [ 0.401724] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint300machine # [ 0.404194] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]301machine # [ 0.405103] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]302machine # [ 0.405998] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]303machine # [ 0.407683] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint304machine # [ 0.409218] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]305machine # [ 0.410122] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]306machine # [ 0.411001] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]307machine # [ 0.412193] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]308machine # [ 0.413737] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint309machine # [ 0.415022] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]310machine # [ 0.416002] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]311machine # [ 0.417655] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint312machine # [ 0.419226] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]313machine # [ 0.420208] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]314machine # [ 0.421767] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint315machine # [ 0.423199] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]316machine # [ 0.424192] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]317machine # [ 0.425791] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint318machine # [ 0.427188] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]319machine # [ 0.428208] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]320machine # [ 0.429757] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint321machine # [ 0.431203] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]322machine # [ 0.432077] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]323machine # [ 0.432986] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]324machine # [ 0.434680] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint325machine # [ 0.436220] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]326machine # [ 0.437132] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]327machine # [ 0.438012] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]328machine # [ 0.439666] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint329machine # [ 0.440859] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]330machine # [ 0.441387] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint331machine # [ 0.443168] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]332machine # [ 0.444095] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint333machine # [ 0.445724] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]334machine # [ 0.446446] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint335machine # [ 0.448745] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]336machine # [ 0.449430] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint337machine # [ 0.450567] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO338machine # [ 0.451454] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint339machine # [ 0.453018] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]340machine # [ 0.453896] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]341machine # [ 0.454507] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint342machine # [ 0.455812] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]343machine # [ 0.459520] ACPI: PCI: Interrupt link LNKA configured for IRQ 10344machine # [ 0.460293] ACPI: PCI: Interrupt link LNKB configured for IRQ 10345machine # [ 0.461289] ACPI: PCI: Interrupt link LNKC configured for IRQ 11346machine # [ 0.462282] ACPI: PCI: Interrupt link LNKD configured for IRQ 11347machine # [ 0.463282] ACPI: PCI: Interrupt link LNKE configured for IRQ 10348machine # [ 0.464313] ACPI: PCI: Interrupt link LNKF configured for IRQ 10349machine # [ 0.465288] ACPI: PCI: Interrupt link LNKG configured for IRQ 11350machine # [ 0.466283] ACPI: PCI: Interrupt link LNKH configured for IRQ 11351machine # [ 0.467229] ACPI: PCI: Interrupt link GSIA configured for IRQ 16352machine # [ 0.468205] ACPI: PCI: Interrupt link GSIB configured for IRQ 17353machine # [ 0.469200] ACPI: PCI: Interrupt link GSIC configured for IRQ 18354machine # [ 0.470202] ACPI: PCI: Interrupt link GSID configured for IRQ 19355machine # [ 0.471199] ACPI: PCI: Interrupt link GSIE configured for IRQ 20356machine # [ 0.472202] ACPI: PCI: Interrupt link GSIF configured for IRQ 21357machine # [ 0.473200] ACPI: PCI: Interrupt link GSIG configured for IRQ 22358machine # [ 0.474200] ACPI: PCI: Interrupt link GSIH configured for IRQ 23359machine # [ 0.476243] iommu: Default domain type: Translated360machine # [ 0.477074] iommu: DMA domain TLB invalidation policy: lazy mode361machine # [ 0.478275] ACPI: bus type USB registered362machine # [ 0.479046] usbcore: registered new interface driver usbfs363machine # [ 0.479918] usbcore: registered new interface driver hub364machine # [ 0.480196] usbcore: registered new device driver usb365machine # [ 0.482697] NetLabel: Initializing366machine # [ 0.483188] NetLabel: domain hash size = 128367machine # [ 0.483958] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO368machine # [ 0.484229] NetLabel: unlabeled traffic allowed by default369machine # [ 0.485195] PCI: Using ACPI for IRQ routing370machine # [ 0.529082] pci 0000:00:01.0: vgaarb: setting as boot VGA device371machine # [ 0.529183] pci 0000:00:01.0: vgaarb: bridge control possible372machine # [ 0.529183] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none373machine # [ 0.529191] vgaarb: loaded374machine # [ 0.529957] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0375machine # [ 0.530187] hpet0: 3 comparators, 64-bit 100.000000 MHz counter376machine # [ 0.534278] clocksource: Switched to clocksource kvm-clock377machine # [ 0.537903] VFS: Disk quotas dquot_6.6.0378machine # [ 0.538649] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)379machine # [ 0.540050] pnp: PnP ACPI init380machine # [ 0.540914] ACPI: IRQ 4 override to edge(!), high(!)381machine # [ 0.541908] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved382machine # [ 0.543502] pnp: PnP ACPI: found 5 devices383machine # [ 0.551108] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns384machine # [ 0.552622] clocksource: Switched to clocksource acpi_pm385machine # [ 0.553634] NET: Registered PF_INET protocol family386machine # [ 0.554729] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)387machine # [ 0.572668] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)388machine # [ 0.574346] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)389machine # [ 0.575705] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)390machine # [ 0.577057] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)391machine # [ 0.578337] TCP: Hash tables configured (established 8192 bind 8192)392machine # [ 0.579514] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)393machine # [ 0.580896] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)394machine # [ 0.582058] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)395machine # [ 0.583297] NET: Registered PF_UNIX/PF_LOCAL protocol family396machine # [ 0.584286] NET: Registered PF_XDP protocol family397machine # [ 0.585178] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]398machine # [ 0.586227] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]399machine # [ 0.587282] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]400machine # [ 0.588422] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]401machine # [ 0.589561] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]402machine # [ 0.590741] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]403machine # [ 0.592482] ACPI: \_SB_.GSIA: Enabled at IRQ 16404machine # [ 0.594902] ACPI: \_SB_.GSIB: Enabled at IRQ 17405machine # [ 0.597176] ACPI: \_SB_.GSIC: Enabled at IRQ 18406machine # [ 0.599160] ACPI: \_SB_.GSID: Enabled at IRQ 19407machine # [ 0.600871] PCI: CLS 0 bytes, default 64408machine # [ 0.601796] Trying to unpack rootfs image as initramfs...409machine # [ 0.607860] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns410machine # [ 0.648892] Initialise system trusted keyrings411machine # [ 0.651855] workingset: timestamp_bits=40 max_order=18 bucket_order=0412machine # [ 0.673469] Key type asymmetric registered413machine # [ 0.675719] Asymmetric key parser 'x509' registered414machine # [ 0.676612] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)415machine # [ 0.679831] io scheduler mq-deadline registered416machine # [ 0.680582] io scheduler kyber registered417machine # [ 0.684894] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled418machine # [ 0.686210] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A419machine # [ 0.691468] Linux agpgart interface v0.103420machine # [ 0.692242] ACPI: bus type drm_connector registered421machine # [ 0.695149] usbcore: registered new interface driver usbserial_generic422machine # [ 0.696272] usbserial: USB Serial support registered for generic423machine # [ 0.699709] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.424machine # [ 0.699709] Please enable it if your BIOS has the CPPC option.425machine # [ 0.701972] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled426machine # [ 0.704831] drop_monitor: Initializing network drop monitor service427machine # [ 0.706036] NET: Registered PF_INET6 protocol family428machine # [ 0.710808] Segment Routing with IPv6429machine # [ 0.711454] In-situ OAM (IOAM) with IPv6430machine # [ 0.713990] IPI shorthand broadcast: enabled431machine # [ 0.723298] sched_clock: Marking stable (580014090, 142674592)->(796876675, -74187993)432machine # [ 0.727789] registered taskstats version 1433machine # [ 0.728732] Loading compiled-in X.509 certificates434machine # [ 0.745422] Demotion targets for Node 0: null435machine # [ 0.747741] Key type .fscrypt registered436machine # [ 0.748423] Key type fscrypt-provisioning registered437machine # [ 0.749386] ima: No TPM chip found, activating TPM-bypass!438machine # [ 0.753721] ima: Allocated hash algorithm: sha1439machine # [ 0.754504] ima: No architecture policies found440machine # [ 0.756705] PM: Magic number: 14:43:512441machine # [ 0.758340] RAS: Correctable Errors collector initialized.442machine # [ 0.767449] clk: Disabling unused clocks443machine # [ 0.771704] PM: genpd: Disabling unused power domains444machine # [ 0.945878] Freeing initrd memory: 29204K445machine # [ 0.949193] Freeing unused decrypted memory: 2028K446machine # [ 0.952075] Freeing unused kernel image (initmem) memory: 3656K447machine # [ 0.953215] Write protecting the kernel read-only data: 32768k448machine # [ 0.954571] Freeing unused kernel image (text/rodata gap) memory: 308K449machine # [ 0.956154] Freeing unused kernel image (rodata/data gap) memory: 696K450machine # [ 1.006874] x86/mm: Checked W+X mappings: passed, no W+X pages found.451machine # [ 1.007996] Run /init as init process452machine # [ 1.019040] systemd[1]: Inserted module 'autofs4'453machine # [ 1.039510] fuse: init (API version 7.45)454machine # [ 1.048062] ACPI: \_SB_.GSIG: Enabled at IRQ 22455machine # [ 1.050761] ACPI: \_SB_.GSIH: Enabled at IRQ 23456machine # [ 1.054146] ACPI: \_SB_.GSIE: Enabled at IRQ 20457machine # [ 1.056455] ACPI: \_SB_.GSIF: Enabled at IRQ 21458machine # [ 1.062270] virtiofs virtio5: discovered new tag: nix-store459machine # [ 1.063840] virtiofs virtio5: virtio_fs_setup_dax: No cache capability460machine # [ 1.071586] virtiofs virtio6: discovered new tag: shared461machine # [ 1.073078] virtiofs virtio6: virtio_fs_setup_dax: No cache capability462machine # [ 1.076450] virtiofs virtio7: discovered new tag: xchg463machine # [ 1.077929] virtiofs virtio7: virtio_fs_setup_dax: No cache capability464machine # [ 1.099753] systemd[1]: Successfully made /usr/ read-only.465machine # [ 1.101476] systemd[1]: systemd 263-0794ba0 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)466machine # [ 1.106408] systemd[1]: Detected virtualization kvm.467machine # [ 1.107262] systemd[1]: Detected architecture x86-64.468machine # [ 1.108136] systemd[1]: Running in initrd.469machine # [ 1.109132] systemd[1]: Initializing machine ID from random generator.470machine # [ 1.110302] systemd[1]: Hostname set to <machine>.471machine # [ 1.182534] systemd[1]: bpf-restrict-fs: LSM BPF program attached472machine # [ 1.220097] systemd[1]: Queued start job for default target Initrd Default Target.473machine # [ 1.223987] systemd[1]: Created slice Slice /system/modprobe.474machine # [ 1.225182] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.475machine # [ 1.226537] systemd[1]: Expecting device /dev/disk/by-label/nixos...476machine # [ 1.227622] systemd[1]: Reached target Path Units.477machine # [ 1.228460] systemd[1]: Reached target Slice Units.478machine # [ 1.229295] systemd[1]: Reached target Swaps.479machine # [ 1.230097] systemd[1]: Reached target Timer Units.480machine # [ 1.231073] systemd[1]: Listening on D-Bus System Message Bus Socket.481machine # [ 1.232245] systemd[1]: Listening on Journal Socket (/dev/log).482machine # [ 1.233351] systemd[1]: Listening on Journal Sockets.483machine # [ 1.234268] systemd[1]: Listening on udev Kernel Socket.484machine # [ 1.235172] systemd[1]: Reached target Socket Units.485machine # [ 1.236922] systemd[1]: Starting Create List of Static Device Nodes...486machine # [ 1.240531] systemd[1]: Starting Load Kernel Module configfs...487machine # [ 1.250905] systemd[1]: Starting Journal Service...488machine # [ 1.262786] systemd[1]: Starting Load Kernel Modules...489machine # [ 1.280743] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os490machine # [ 1.287808] systemd[1]: Starting Coldplug All udev Devices...491machine # [ 1.301102] systemd[1]: Finished Create List of Static Device Nodes.492machine # [ 1.306407] systemd[1]: modprobe@configfs.service: Deactivated successfully.493machine # [ 1.314019] systemd-journald[65]: Collecting audit messages is disabled.494machine # [ 1.315634] systemd[1]: Finished Load Kernel Module configfs.495machine # [ 1.320199] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config496machine # [ 1.332787] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...497machine # [ 1.335216] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.498machine # [ 1.344721] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev499machine # [ 1.377316] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.500machine # [ 1.385899] systemd[1]: Starting Create Static Device Nodes in /dev...501machine # [ 1.396275] systemd[1]: Finished Load Kernel Modules.502machine # [ 1.405397] systemd[1]: Starting Apply Kernel Variables...503machine # [ 1.425254] systemd[1]: Finished Create Static Device Nodes in /dev.504machine # [ 1.430910] systemd[1]: Reached target Preparation for Local File Systems.505machine # [ 1.293391] systemd-modules-load[66]: Inserted module 'dm_mod'[ 1.437271] systemd[1]: Reached target Local File Systems.506machine # 507machine # [ 1.297233] systemd-modules-load[66]: Inserted module 'virtio_balloon'508machine # [ 1.298321] systemd-modules-load[66]: Inserted module 'virtio_gpu'509machine # [ 1.445253] systemd[1]: Starting Rule-based Manager for Device Events and Files...510machine # [ 1.451983] systemd[1]: Started Journal Service.511machine # [ 1.315183] systemd[1]: Finished Apply Kernel Variables.512machine # [ 1.326587] systemd[1]: Starting Create System Files and Directories...513machine # [ 1.355080] systemd[1]: Finished Create System Files and Directories.514machine # [ 1.367368] systemd-udevd[73]: Using default interface naming scheme 'v261'.515machine # [ 1.389704] systemd[1]: Started Rule-based Manager for Device Events and Files.516machine # [ 1.446787] systemd[1]: Finished Coldplug All udev Devices.517machine # [ 1.447759] systemd[1]: Reached target System Initialization.518machine # [ 1.449203] systemd[1]: Reached target Basic System.519machine # [ 1.775089] virtio_blk virtio2: 1/0/0 default/read/poll queues520machine # [ 1.785445] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)521machine # [ 1.787192] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12522machine # [ 1.798408] serio: i8042 KBD port at 0x60,0x64 irq 1523machine # [ 1.799137] serio: i8042 AUX port at 0x60,0x64 irq 12524machine # [ 1.805898] ehci-pci 0000:00:1d.7: EHCI Host Controller525machine # [ 1.806866] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1526machine # [ 1.808848] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000527machine # [ 1.814720] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00528machine # [ 1.816313] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18529machine # [ 1.817467] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1530machine # [ 1.821692] usb usb1: Product: EHCI Host Controller531machine # [ 1.822336] usb usb1: Manufacturer: Linux 6.18.54 ehci_hcd532machine # [ 1.823713] usb usb1: SerialNumber: 0000:00:1d.7533machine # [ 1.826068] hub 1-0:1.0: USB hub found534machine # [ 1.827103] hub 1-0:1.0: 6 ports detected535machine # [ 1.831861] uhci_hcd 0000:00:1d.0: UHCI Host Controller536machine # [ 1.832666] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2537machine # [ 1.839439] uhci_hcd 0000:00:1d.0: detected 2 ports538machine # [ 1.852511] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c180539machine # [ 1.857312] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18540machine # [ 1.858488] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1541machine # [ 1.873525] usb usb2: Product: UHCI Host Controller542machine # [ 1.875215] usb usb2: Manufacturer: Linux 6.18.54 uhci_hcd543machine # [ 1.882753] usb usb2: SerialNumber: 0000:00:1d.0544machine # [ 1.886608] SCSI subsystem initialized545machine # [ 1.891401] hub 2-0:1.0: USB hub found546machine # [ 1.894706] hub 2-0:1.0: 2 ports detected547machine # [ 1.762498] systemd[1]: Starting Virtual Console Setup...548machine # [ 1.910404] uhci_hcd 0000:00:1d.1: UHCI Host Controller549machine # [ 1.781807] (udev-worker)[94]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.550machine # [ 1.784039] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.551machine # [ 1.795751] systemd-vconsole-setup[97]: Configuration of first virtual console was skipped, ignoring remaining ones.552machine # [ 1.941065] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0553machine # [ 1.946784] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3554machine # [ 1.808064] systemd[1]: Finished Virtual Console Setup.555machine # [ 1.960293] uhci_hcd 0000:00:1d.1: detected 2 ports556machine # [ 1.964663] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a0557machine # [ 1.970480] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18558machine # [ 1.971636] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1559machine # [ 1.831068] systemd[1]: Found device /dev/disk/by-label/nixos.560machine # [ 1.831853] systemd[1]: Reached target Initrd Root Device.561machine # [ 1.834066] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...562machine # [ 1.988792] usb usb3: Product: UHCI Host Controller563machine # [ 1.989631] usb usb3: Manufacturer: Linux 6.18.54 uhci_hcd564machine # [ 1.991884] usb usb3: SerialNumber: 0000:00:1d.1565machine # [ 1.996135] hub 3-0:1.0: USB hub found566machine # [ 1.997368] hub 3-0:1.0: 2 ports detected567machine # [ 2.001861] uhci_hcd 0000:00:1d.2: UHCI Host Controller568machine # [ 2.003744] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4569machine # [ 2.006737] uhci_hcd 0000:00:1d.2: detected 2 ports570machine # [ 2.007543] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c0571machine # [ 2.011243] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18572machine # [ 2.015787] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1573machine # [ 1.876310] systemd-fsck[104]: nixos: clean, 12/65536 files, 13019/262144 blocks574machine # [ 2.020066] usb usb4: Product: UHCI Host Controller575machine # [ 2.021563] usb usb4: Manufacturer: Linux 6.18.54 uhci_hcd576machine # [ 1.883095] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.577machine # [ 2.028880] usb usb4: SerialNumber: 0000:00:1d.2578machine # [ 2.031632] hub 4-0:1.0: USB hub found579machine # [ 2.032444] hub 4-0:1.0: 2 ports detected580machine # [ 2.061094] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode581machine # [ 2.062266] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)582machine # [ 2.064750] usb 1-1: new high-speed USB device number 2 using ehci-pci583machine # [ 2.070748] ahci 0000:00:1f.2: flags: 64bit ncq only584machine # [ 2.077539] scsi host0: ahci585machine # [ 2.079226] scsi host1: ahci586machine # [ 2.080616] scsi host2: ahci587machine # [ 2.082310] scsi host3: ahci588machine # [ 2.083904] scsi host4: ahci589machine # [ 2.085392] scsi host5: ahci590machine # [ 2.086441] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 1591machine # [ 2.088199] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 1592machine # [ 2.089364] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 1593machine # [ 2.090627] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 1594machine # [ 2.091869] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 1595machine # [ 2.093047] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 1596machine # [ 2.194854] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00597machine # [ 2.197730] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10598machine # [ 2.200818] usb 1-1: Product: QEMU USB Tablet599machine # [ 2.202575] usb 1-1: Manufacturer: QEMU600machine # [ 2.204214] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1601machine # [ 2.241432] hid: raw HID events driver (C) Jiri Kosina602machine # [ 2.154768] systemd[1]: Mounting /sysroot...603machine # [ 2.402854] ata1: SATA link down (SStatus 0 SControl 300)604machine # [ 2.410178] ata5: SATA link down (SStatus 0 SControl 300)605machine # [ 2.411091] ata6: SATA link down (SStatus 0 SControl 300)606machine # [ 2.412041] ata2: SATA link down (SStatus 0 SControl 300)607machine # [ 2.412977] ata4: SATA link down (SStatus 0 SControl 300)608machine # [ 2.413905] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)609machine # [ 2.414858] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100610machine # [ 2.415615] ata3.00: applying bridge limits611machine # [ 2.416342] ata3.00: configured for UDMA/100612machine # [ 2.417417] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5613machine # [ 2.476803] usbcore: registered new interface driver usbhid614machine # [ 2.477777] usbhid: USB HID core driver615machine # [ 2.492909] 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/input2616machine # [ 2.497968] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0617machine # [ 2.502798] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray618machine # [ 2.520945] cdrom: Uniform CD-ROM driver Revision: 3.20619machine # [ 2.533917] EXT4-fs (vda): mounted filesystem f4593f0d-0a32-4ebf-896c-1d0acd2ea957 r/w with ordered data mode. Quota mode: none.620machine # [ 2.396768] systemd[1]: Mounted /sysroot.621machine # [ 2.399227] systemd[1]: Reached target Initrd Root File System.622machine # [ 2.401225] systemd[1]: Starting Mountpoints Configured in the Real Root...623machine # [ 2.415320] systemd-sysroot-fstab-check[137]: /sysroot should be mounted in the initrd, will request daemon-reload.624machine # [ 2.418149] systemd[1]: Reload requested from client PID 137 ('systemd-sysroot') (unit initrd-parse-etc.service)...625machine # [ 2.420526] systemd[1]: Reloading...626machine # [ 2.495464] systemd[1]: Reloading finished in 74 ms.627machine # [ 2.504197] systemd-sysroot-fstab-check[137]: Requesting initrd-fs.target/start/replace...628machine # [ 2.507147] systemd-sysroot-fstab-check[137]: Requesting swap.target/start/replace...629machine # [ 2.511377] systemd[1]: initrd-parse-etc.service: Deactivated successfully.630machine # [ 2.512769] systemd[1]: Finished Mountpoints Configured in the Real Root.631machine # [ 2.513853] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.632machine # [ 3.156170] systemd[1]: Mounting /sysroot/nix/.ro-store...633machine # [ 3.162382] systemd[1]: Mounting /sysroot/nix/.rw-store...634machine # [ 3.171354] systemd[1]: Mounting /sysroot/run...635machine # [ 3.181311] systemd[1]: Mounting /sysroot/tmp/shared...636machine # [ 3.191146] systemd[1]: Mounting /sysroot/tmp/xchg...637machine # [ 3.228433] systemd[1]: Mounted /sysroot/nix/.ro-store.638machine # [ 3.230866] systemd[1]: Mounted /sysroot/nix/.rw-store.639machine # [ 3.238330] systemd[1]: Mounted /sysroot/run.640machine # [ 3.239939] systemd[1]: Mounted /sysroot/tmp/shared.641machine # [ 3.240918] systemd[1]: Mounted /sysroot/tmp/xchg.642machine # [ 3.244882] systemd[1]: Starting rw-sysroot-nix-store.service...643machine # [ 3.255458] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.644machine # [ 3.256927] systemd[1]: Finished rw-sysroot-nix-store.service.645machine # [ 4.155670] systemd[1]: Mounting /sysroot/nix/store...646machine # [ 4.179630] systemd[1]: Mounted /sysroot/nix/store.647machine # [ 4.181272] systemd[1]: Reached target Initrd File Systems.648machine # [ 4.183101] systemd[1]: Starting Find NixOS closure...649machine # [ 4.185653] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...650machine # [ 4.208140] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.651machine # [ 4.210724] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.652machine # [ 4.216973] systemd[1]: Finished Find NixOS closure.653machine # [ 4.218146] systemd[1]: Reached target Initrd Default Target.654machine # [ 4.219906] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...655machine # [ 4.235701] systemd[1]: initrd-cleanup.service: Deactivated successfully.656machine # [ 4.237138] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.657machine # [ 4.239957] systemd[1]: Stopped target Initrd Default Target.658machine # [ 4.241244] systemd[1]: Stopped target Basic System.659machine # [ 4.242432] systemd[1]: Stopped target Initrd Root Device.660machine # [ 4.244135] systemd[1]: Stopped target Path Units.661machine # [ 4.244789] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.662machine # [ 4.245790] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.663machine # [ 4.246815] systemd[1]: Stopped target Slice Units.664machine # [ 4.247528] systemd[1]: Stopped target Socket Units.665machine # [ 4.248301] systemd[1]: Stopped target System Initialization.666machine # [ 4.249071] systemd[1]: Stopped target Swaps.667machine # [ 4.249694] systemd[1]: Stopped target Timer Units.668machine # [ 4.251145] systemd[1]: dbus.socket: Deactivated successfully.669machine # [ 4.252214] systemd[1]: Closed D-Bus System Message Bus Socket.670machine # [ 4.254051] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.671machine # [ 4.255049] systemd[1]: Stopped Find NixOS closure.672machine # [ 4.255699] systemd[1]: Starting rw-sysroot-nix-store.service...673machine # [ 4.257504] systemd[1]: systemd-sysctl.service: Deactivated successfully.674machine # [ 4.258440] systemd[1]: Stopped Apply Kernel Variables.675machine # [ 4.259223] systemd[1]: systemd-modules-load.service: Deactivated successfully.676machine # [ 4.260563] systemd[1]: Stopped Load Kernel Modules.677machine # [ 4.262148] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.678machine # [ 4.263567] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.679machine # [ 4.264610] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.680machine # [ 4.265969] systemd[1]: Stopped Create System Files and Directories.681machine # [ 4.267113] systemd[1]: Stopped target Local File Systems.682machine # [ 4.268212] systemd[1]: Stopped target Preparation for Local File Systems.683machine # [ 4.270499] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.684machine # [ 4.271463] systemd[1]: Stopped Coldplug All udev Devices.685machine # [ 4.272213] systemd[1]: Stopping Rule-based Manager for Device Events and Files...686machine # [ 4.273206] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.687machine # [ 4.274185] systemd[1]: Stopped Virtual Console Setup.688machine # [ 4.284420] systemd[1]: systemd-udevd.service: Deactivated successfully.689machine # [ 4.286989] systemd[1]: Stopped Rule-based Manager for Device Events and Files.690machine # [ 4.289692] systemd[1]: Starting Cleanup udev Database...691machine # [ 4.290476] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.692machine # [ 4.291467] systemd[1]: Stopped Create Static Device Nodes in /dev.693machine # [ 4.292291] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.694machine # [ 4.293324] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.695machine # [ 4.294279] systemd[1]: kmod-static-nodes.service: Deactivated successfully.696machine # [ 4.297147] systemd[1]: Stopped Create List of Static Device Nodes.697machine # [ 4.299526] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.698machine # [ 4.302127] systemd[1]: Finished rw-sysroot-nix-store.service.699machine # [ 4.316814] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.700machine # [ 4.319073] systemd[1]: Finished Cleanup udev Database.701machine # [ 4.319780] systemd[1]: Reached target Switch Root.702machine # [ 4.321122] systemd[1]: Starting NixOS Activation...703machine # [ 4.392586] initrd-nixos-activation-start[189]: booting system configuration /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test704machine # [ 4.419382] initrd-nixos-activation-start[189]: running activation script...705machine # [ 4.590722] initrd-nixos-activation-start[212]: setting up /etc...706machine # [ 4.679082] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.707machine # [ 4.681071] systemd[1]: Finished NixOS Activation.708machine # [ 4.682736] systemd[1]: Starting Switch Root...709machine # [ 4.696772] systemd[1]: Switching root.710machine # [ 4.964851] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).711machine # [ 5.017684] NET: Registered PF_VSOCK protocol family712machine # [ 5.037487] systemd[1]: systemd 263-0794ba0 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)713machine # [ 5.041365] systemd[1]: Detected virtualization kvm.714machine # [ 5.042063] systemd[1]: Detected architecture x86-64.715machine # [ 5.042839] systemd[1]: Detected first boot.716machine # [ 5.044226] systemd[1]: Initializing machine ID from random generator.717machine # [ 5.128916] systemd[1]: bpf-restrict-fs: LSM BPF program attached718machine # [ 5.214760] systemd[1]: Applying preset policy.719machine # [ 5.364619] systemd[1]: Populated /etc with preset unit settings.720machine # [ 5.549739] systemd[1]: initrd-switch-root.service: Deactivated successfully.721machine # [ 5.551048] systemd[1]: Stopped initrd-switch-root.service.722machine # [ 5.553182] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.723machine # [ 5.555270] systemd[1]: Created slice Slice /system/getty.724machine # [ 5.556599] systemd[1]: Created slice User and Session Slice.725machine # [ 5.557518] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.726machine # [ 5.558767] systemd[1]: Started Forward Password Requests to Wall Directory Watch.727machine # [ 5.559856] systemd[1]: Expecting device /dev/hvc0...728machine # [ 5.560543] systemd[1]: Expecting device /dev/ttyS0...729machine # [ 5.561284] systemd[1]: Reached target Local Encrypted Volumes.730machine # [ 5.562133] systemd[1]: Stopped target initrd-fs.target.731machine # [ 5.562930] systemd[1]: Stopped target initrd-root-fs.target.732machine # [ 5.563750] systemd[1]: Stopped target initrd-switch-root.target.733machine # [ 5.564583] systemd[1]: Reached target Virtual Machines and Containers.734machine # [ 5.565475] systemd[1]: Reached target Path Units.735machine # [ 5.566201] systemd[1]: Reached target Remote File Systems.736machine # [ 5.567066] systemd[1]: Reached target Slice Units.737machine # [ 5.567803] systemd[1]: Reached target Swaps.738machine # [ 5.569424] systemd[1]: Listening on Query the User Interactively for a Password.739machine # [ 5.583447] systemd[1]: Listening on Process Core Dump Socket.740machine # [ 5.585115] systemd[1]: Listening on Credential Encryption/Decryption.741machine # [ 5.586877] systemd[1]: Listening on Factory Reset Management.742machine # [ 5.587835] systemd[1]: Listening on Hostname Service Socket.743machine # [ 5.590545] systemd[1]: Starting Journal Log Access Socket...744machine # [ 5.592136] systemd[1]: Listening on Journal Audit Socket.745machine # [ 5.595231] systemd[1]: Listening on Console Output Muting Service Socket.746machine # [ 5.596350] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.747machine # [ 5.597440] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os748machine # [ 5.598771] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki749machine # [ 5.602521] systemd[1]: Listening on Disk Repartitioning Service Socket.750machine # [ 5.603544] systemd[1]: Listening on udev Varlink Socket.751machine # [ 5.606047] systemd[1]: Mounting Huge Pages File System...752machine # [ 5.608329] systemd[1]: Mounting POSIX Message Queue File System...753machine # [ 5.615120] systemd[1]: Mounting Kernel Debug File System...754machine # [ 5.623912] systemd[1]: Mounting Kernel Trace File System...755machine # [ 5.633063] systemd[1]: Starting Create List of Static Device Nodes...756machine # [ 5.643799] systemd[1]: Starting Load Kernel Module configfs...757machine # [ 5.644643] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm758machine # [ 5.660910] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore759machine # [ 5.673024] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse760machine # [ 5.692144] systemd[1]: Mounting FUSE Control File System...761machine # [ 5.694263] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67762machine # [ 5.709185] systemd[1]: Starting Journal Service...763machine # [ 5.720140] systemd[1]: Starting Load Kernel Modules...764machine # [ 5.730037] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...765machine # [ 5.746241] systemd[1]: Starting Remount Root and Kernel File Systems...766machine # [ 5.750124] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os767machine # [ 5.766759] systemd[1]: Starting Coldplug All udev Devices...768machine # [ 5.771774] systemd-journald[283]: Collecting audit messages is enabled.769machine # [ 5.780379] systemd[1]: Listening on Journal Log Access Socket.770machine # [ 5.785047] systemd[1]: Mounted Huge Pages File System.771machine # [ 5.788113] systemd[1]: Mounted POSIX Message Queue File System.772machine # [ 5.652134] systemd[1]: Queued start job for default target Multi-User System.773machine # [ 5.653690] systemd[1]: systemd-journald.service: Deactivated successfully.774machine # [ 5.800563] systemd[1]: Started Journal Service.775machine # [ 5.666842] systemd[1]: Mounted Kernel Debug File System.776machine # [ 5.675586] systemd[1]: Mounted Kernel Trace File System.777machine # [ 5.678068] systemd[1]: Finished Create List of Static Device Nodes.778machine # [ 5.679860] systemd[1]: modprobe@configfs.service: Deactivated successfully.779machine # [ 5.827036] loop: module loaded780machine # [ 5.685076] systemd[1]: Finished Load Kernel Module configfs.781machine # [ 5.685943] systemd[1]: Mounted FUSE Control File System.782machine # [ 5.686961] systemd-modules-load[284]: Inserted module 'loop'783machine # [ 5.699268] systemd[1]: Finished Load Kernel Modules.784machine # [ 5.852904] EXT4-fs (vda): re-mounted f4593f0d-0a32-4ebf-896c-1d0acd2ea957.785machine # [ 5.714099] systemd[1]: Mounting Kernel Configuration File System...786machine # [ 5.717203] systemd[1]: Starting Firewall...787machine # [ 5.729142] systemd[1]: Starting Apply Kernel Variables...788machine # [ 5.735815] systemd-oomd[285]: No swap; memory pressure usage will be degraded789machine # [ 5.744072] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...790machine # [ 5.750060] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.791machine # [ 5.760362] systemd[1]: Finished Remount Root and Kernel File Systems.792machine # [ 5.774565] systemd[1]: Listening on Disk Image Download Service Socket.793machine # [ 5.785311] systemd[1]: Starting Flush Journal to Persistent Storage...794machine # [ 5.786323] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore795machine # [ 5.795111] systemd[1]: Starting Load/Save OS Random Seed...796machine # [ 5.795899] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os797machine # [ 5.857703] systemd[1]: Mounted Kernel Configuration File System.798machine # [ 6.005550] systemd-journald[283]: Received client request to flush runtime journal.799machine # [ 5.942427] systemd[1]: Finished Apply Kernel Variables.800machine # [ 5.943425] systemd[1]: Finished Load/Save OS Random Seed.801machine # [ 5.944789] systemd[1]: Reached target First Boot Complete.802machine # [ 5.950140] systemd[1]: Finished Flush Journal to Persistent Storage.803machine # [ 5.961204] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.804machine # [ 5.965141] systemd[1]: Starting Create Static Device Nodes in /dev...805machine # [ 6.057155] systemd[1]: Finished Coldplug All udev Devices.806machine # [ 6.070947] systemd[1]: Finished Create Static Device Nodes in /dev.807machine # [ 6.072698] systemd[1]: Reached target Preparation for Local File Systems.808machine # [ 6.076877] systemd[1]: Starting Rule-based Manager for Device Events and Files...809machine # [ 6.122819] systemd-udevd[324]: Using default interface naming scheme 'v261'.810machine # [ 6.170102] systemd[1]: Started Rule-based Manager for Device Events and Files.811machine # [ 6.287909] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse812machine # [ 6.374717] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.813machine # [ 6.407768] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.814machine # [ 6.413861] systemd[1]: Mounting /run/wrappers...815machine # [ 6.457832] systemd[1]: Mounted /run/wrappers.816machine # [ 6.460041] systemd[1]: Reached target Local File Systems.817machine # [ 6.463472] systemd[1]: Listening on Boot Loader Control Service Socket.818machine # [ 6.468125] systemd[1]: Starting Create SUID/SGID Wrappers...819machine # [ 6.468939] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.820machine # [ 6.472065] systemd[1]: Starting Save Transient machine-id to Disk...821machine # [ 6.475220] systemd[1]: Starting Create System Files and Directories...822machine # [ 6.479339] (udev-worker)[346]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.823machine # [ 6.482141] (udev-worker)[346]: Network interface NamePolicy= disabled on kernel command line.824machine # [ 6.575988] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.825machine # [ 6.584619] systemd[1]: Finished Save Transient machine-id to Disk.826machine # [ 6.608137] systemd[1]: Finished Create System Files and Directories.827machine # [ 6.616375] systemd[1]: Starting Rebuild Journal Catalog...828machine # [ 6.621230] systemd[1]: Starting Record System Boot/Shutdown in UTMP...829machine # [ 6.709549] systemd[1]: Finished Record System Boot/Shutdown in UTMP.830machine # [ 6.737478] systemd[1]: Condition check resulted in Virtio network device being skipped.831machine # [ 6.740916] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore832machine # [ 6.742362] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.833machine # [ 6.744671] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67834machine # [ 6.747414] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore835machine # [ 6.750147] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os836machine # [ 6.752316] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os837machine # [ 6.755159] systemd[1]: Finished Rebuild Journal Catalog.838machine # [ 6.760551] systemd[1]: Starting Update is Completed...839machine # [ 6.817700] systemd[1]: Finished Update is Completed.840machine # [ 7.071257] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3841machine # [ 7.072484] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console842machine # [ 6.957679] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.843machine # [ 6.963145] systemd[1]: Finished Create SUID/SGID Wrappers.844machine # [ 6.964129] systemd[1]: Reached target System Initialization.845machine # [ 6.966571] systemd[1]: Started Discard unused filesystem blocks once a week.846machine # [ 6.968544] systemd[1]: Started Daily Cleanup of Temporary Directories.847machine # [ 6.970165] systemd[1]: Reached target Timer Units.848machine # [ 6.970838] systemd[1]: Listening on D-Bus System Message Bus Socket.849machine # [ 6.972750] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.850machine # [ 6.974727] systemd[1]: Reached target Socket Units.851machine # [ 6.976193] systemd[1]: Reached target Basic System.852machine # [ 6.980220] systemd[1]: Started backdoor.service.853machine # [ 6.985147] systemd[1]: Starting Import lastlog data into lastlog2 database...854machine # [ 6.995534] systemd[1]: Starting Name Service Cache Daemon (nsncd)...855machine # [ 7.006121] systemd[1]: Starting Post-Boot Actions...856machine # [ 7.018607] systemd[1]: Started Reset console on configuration changes.857machine # [ 7.046813] systemd[1]: Starting resolvconf update...858machine # [ 7.089473] systemd[1]: Starting D-Bus System Message Bus...859machine # connecting to host...860machine # [ 7.152728] systemd[1]: Finished Post-Boot Actions.861machine # [ 7.175379] nsncd[483]: Oct 03 03:32:34.172 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"862machine # [ 7.177447] systemd[1]: Started Name Service Cache Daemon (nsncd).863machine # [ 7.180140] systemd[1]: Reached target Host and Network Name Lookups.864machine # [ 7.182670] systemd[1]: Reached target User and Group Name Lookups.865machine # [ 7.196437] systemd[1]: Starting User Login Management...866machine # [ 7.199345] systemd[1]: Finished Firewall.867machine: Guest shell says: b'Spawning backdoor root shell...\n'868machine: connected to guest root shell869machine: (connecting took 7.90 seconds)870machine: (finished: waiting for the VM to finish booting, in 8.25 seconds)871machine # [ 7.220656] systemd[1]: Finished Import lastlog data into lastlog2 database.872machine # [ 7.117151] ACPI: button: Power Button [PWRF]873machine # [ 7.119793] mousedev: PS/2 mouse device common for all mice874machine # [ 7.246561] rtc_cmos PNP0B00:00: RTC can wake from S4875machine # [ 7.365817] rtc_cmos PNP0B00:00: registered as rtc0876machine # [ 7.315432] dbus-broker-launch[492]: Looking up NSS user entry for 'systemd-timesync'...877machine # [ 7.319602] dbus-broker-launch[492]: NSS returned no entry for 'systemd-timesync'878machine # [ 7.325494] dbus-broker-launch[492]: Invalid user-name in /nix/store/y08k2iaf3v8vn0y4c1mn7arhd3jw6srk-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"879machine # [ 7.365885] rtc_cmos PNP0B00:00: setting system clock to 2026-10-03T03:32:33 UTC (1790998353)880machine # [ 7.365981] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs881machine # [ 7.367358] systemd-journald[283]: Time jumped backwards, rotating.882machine # [ 7.407992] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input4883machine # [ 7.425442] parport_pc 00:02: reported by Plug and Play ACPI884machine # [ 7.425590] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]885machine # [ 7.455907] Console: switching to colour dummy device 80x25886machine # [ 7.525393] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized887machine # [ 7.577272] [drm] Found bochs VGA, ID 0xb0c5.888machine # [ 7.577275] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.889machine # [ 7.393376] dbus-broker-launch[492]: Ready890machine # [ 7.437966] systemd-logind[516]: New seat seat0.891machine # [ 7.586106] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic892machine # [ 7.586838] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0893machine # [ 7.593810] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt894machine # [ 7.594501] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD895machine # [ 7.472965] systemd[1]: Started D-Bus System Message Bus.896machine # [ 7.475958] systemd[1]: Started User Login Management.897machine # [ 7.506407] systemd[1]: Starting linger-users.service...898machine # [ 7.534282] systemd[1]: Stopped target Host and Network Name Lookups.899machine # [ 7.535298] systemd[1]: Stopping Host and Network Name Lookups...900machine # [ 7.537249] systemd[1]: Stopped target User and Group Name Lookups.901machine # [ 7.538774] systemd[1]: Stopping User and Group Name Lookups...902machine # [ 7.542239] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...903machine # [ 7.543190] systemd[1]: nscd.service: Deactivated successfully.904machine # [ 7.546108] systemd[1]: Stopped Name Service Cache Daemon (nsncd).905machine # [ 7.571376] systemd[1]: Starting Name Service Cache Daemon (nsncd)...906machine # [ 7.630319] systemd[1]: linger-users.service: Deactivated successfully.907machine # [ 7.633887] systemd[1]: Finished linger-users.service.908machine # [ 7.639270] systemd[1]: Finished resolvconf update.909machine # [ 7.640356] nsncd[585]: Oct 03 03:32:33.914 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"910machine # [ 7.643289] systemd[1]: Started Name Service Cache Daemon (nsncd).911machine # [ 7.647796] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6912machine # [ 7.664197] systemd[1]: Created slice Slice /user/1001.913machine # [ 7.665310] systemd[1]: Reached target Preparation for Network.914machine # [ 7.666196] systemd[1]: Reached target Host and Network Name Lookups.915machine # [ 7.667106] systemd[1]: Reached target User and Group Name Lookups.916machine # [ 7.675282] systemd[1]: Starting DHCP Client...917machine # [ 7.682270] systemd[1]: Starting Address configuration of eth1...918machine # [ 7.648113] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5919machine # [ 7.799110] Console: switching to colour frame buffer device 160x50920machine # [ 7.699101] systemd[1]: Starting Extra networking commands....921machine # [ 7.699877] systemd[1]: Starting Virtual Console Setup...922machine # [ 7.700698] systemd[1]: Starting User Runtime Directory /run/user/1001...923machine # [ 7.872157] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device924machine # [ 7.731439] systemd-logind[516]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)925machine # [ 7.733358] systemd-logind[516]: Watching system buttons on /dev/input/event2 (Power Button)926machine # [ 7.782234] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.927machine # [ 7.783277] systemd[1]: Stopped Virtual Console Setup.928machine # [ 7.788041] systemd[1]: Starting Virtual Console Setup...929machine # [ 7.945130] ppdev: user-space parallel port driver930machine # [ 7.825422] systemd-logind[516]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)931machine # [ 8.023058] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)932machine # [ 7.896899] systemd[1]: Finished User Runtime Directory /run/user/1001.933machine # [ 7.897976] systemd[1]: Starting User Manager for UID 1001...934machine # [ 8.047450] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)935machine # [ 7.920758] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.936machine # [ 7.921899] systemd[1]: Stopped Virtual Console Setup.937machine # [ 7.935978] systemd[1]: Starting Virtual Console Setup...938machine # [ 7.977426] (systemd)[618]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)939machine # [ 7.996896] systemd-logind[516]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.940machine # [ 8.039251] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy941machine # [ 8.229873] systemd[1]: Finished Address configuration of eth1.942machine # [ 8.232338] network-addresses-eth1-start[607]: adding address 192.168.1.1/24... done943machine # [ 8.236188] network-addresses-eth1-start[607]: adding address 2001:db8:1::1/64... done944machine # [ 8.407501] kvm_amd: TSC scaling supported945machine # [ 8.409737] kvm_amd: Nested Virtualization enabled946machine # [ 8.410229] kvm_amd: Nested Paging enabled947machine # [ 8.412895] kvm_amd: LBR virtualization supported948machine # [ 8.413350] kvm_amd: Virtual VMLOAD VMSAVE supported949machine # [ 8.272824] systemd[618]: Queued start job for default target Main User Target.950machine # [ 8.278385] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy951machine # [ 8.279842] systemd[618]: Created slice User Application Slice.952machine # [ 8.280797] systemd[618]: Started Daily Cleanup of User's Temporary Directories.953machine # [ 8.426720] kvm_amd: Virtual GIF supported954machine # [ 8.427127] kvm_amd: Virtual NMI enabled955machine # [ 8.287207] systemd[618]: Reached target Paths.956machine # [ 8.290319] systemd[618]: Reached target Timers.957machine # [ 8.297151] systemd[618]: Starting D-Bus User Message Bus Socket...958machine # [ 8.298217] systemd[618]: Starting Create User Files and Directories...959machine # [ 8.341313] systemd[618]: Finished Create User Files and Directories.960machine # [ 8.348882] dhcpcd[627]: dhcpcd-10.3.2 starting961machine # [ 8.360522] dhcpcd[690]: dev: loaded udev962machine # [ 8.367538] systemd[618]: Listening on D-Bus User Message Bus Socket.963machine # [ 8.371815] systemd[618]: Reached target Sockets.964machine # [ 8.374140] systemd[618]: Reached target Basic System.965machine # [ 8.374825] systemd[1]: Started User Manager for UID 1001.966machine # [ 8.381170] systemd[618]: Starting Run user-specific NixOS activation...967machine # [ 8.530227] EDAC MC: Ver: 3.0.0968machine # [ 8.537058] 8021q: 802.1Q VLAN Support v1.8969machine # [ 8.537554] 8021q: adding VLAN 0 to HW filter on device eth1970machine # [ 8.396118] systemd[1]: Finished Extra networking commands..971machine # [ 8.396886] systemd[1]: Reached target Network.972machine # [ 8.405824] systemd[1]: Starting Permit User Sessions...973machine # [ 8.436705] systemd[618]: Finished Run user-specific NixOS activation.974machine # [ 8.437793] systemd[618]: Reached target Main User Target.975machine # [ 8.440160] systemd[618]: Startup finished in 411ms.976machine # [ 8.450711] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.977machine # [ 8.495236] systemd[1]: Finished Permit User Sessions.978machine # [ 8.507299] systemd[1]: Started Getty on tty1.979machine # [ 8.508022] systemd[1]: Reached target Login Prompts.980machine # [ 8.658735] cfg80211: Loading compiled-in X.509 certificates for regulatory database981machine # [ 8.669373] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'982machine # [ 8.670098] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'983machine # [ 8.672435] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2984machine # [ 8.673296] cfg80211: failed to load regulatory.db985machine # [ 8.710320] 8021q: adding VLAN 0 to HW filter on device eth0986machine # [ 8.569539] dhcpcd[690]: eth0: waiting for carrier987machine # [ 8.570469] dhcpcd[690]: eth0: carrier acquired988machine # [ 8.583902] dhcpcd[690]: DUID 00:01:00:01:32:53:33:d2:52:54:00:12:34:56989machine # [ 8.585074] dhcpcd[690]: eth0: IAID 00:12:34:56990machine # [ 8.585765] dhcpcd[690]: eth0: adding address fe80::5054:ff:fe12:3456991machine # [ 8.626717] systemd-vconsole-setup[622]: Configuration of first virtual console was skipped, ignoring remaining ones.992machine # [ 8.630530] systemd[1]: Finished Virtual Console Setup.993machine # [ 9.855130] dhcpcd[690]: eth0: soliciting a DHCP lease994machine # [ 10.007052] NET: Registered PF_PACKET protocol family995machine # [ 9.868538] dhcpcd[690]: eth0: offered 10.0.2.15 from 10.0.2.2996machine # [ 9.875837] dhcpcd[690]: eth0: probing address 10.0.2.15/24997machine # [ 10.452196] dhcpcd[690]: eth0: soliciting an IPv6 router998machine # [ 10.455209] dhcpcd[690]: eth0: Router Advertisement from fe80::2999machine # [ 10.457363] dhcpcd[690]: eth0: adding address fec0::5054:ff:fe12:3456/641000machine # [ 10.459595] dhcpcd[690]: eth0: adding route to fec0::/641001machine # [ 10.461490] dhcpcd[690]: eth0: adding default route via fe80::21002machine # [ 14.352455] dhcpcd[690]: eth0: leased 10.0.2.15 for 86400 seconds1003machine # [ 14.355472] dhcpcd[690]: eth0: adding route to 10.0.2.0/241004machine # [ 14.357799] dhcpcd[690]: eth0: adding default route via 10.0.2.21005machine # [ 14.442581] systemd[1]: Started DHCP Client.1006machine # [ 14.444494] systemd[1]: Reached target Multi-User System.1007machine # [ 14.445690] systemd[1]: Startup finished in 868ms (kernel) + 3.989s (initrd) + 9.587s (userspace) = 14.445s.1008machine: (finished: waiting for unit multi-user.target, in 16.02 seconds)1009machine: must succeed: /nix/store/3vbcjh6yz1i6lp352svzp92f3jmjppg7-stderr-runner /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/bin/switch-to-configuration test1010machine # Checking switch inhibitors... done1011machine # [ 15.193811] nixos[839]: switching to system configuration /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test1012machine # [ 15.196046] systemd[1]: Stopped target Local File Systems.1013machine # [ 15.197674] systemd[1]: Stopped target Remote File Systems.1014machine # activating the configuration...1015machine # setting up /etc...1016machine # [ 15.418956] systemd[1]: Reload requested from client PID 839 ('.switch-to-conf') (unit backdoor.service)...1017machine # [ 15.420656] systemd[1]: Reloading...1018machine # [ 15.655308] systemd[1]: Reloading finished in 232 ms.1019machine # reloading user units for usertest...1020machine # [ 15.686214] systemd[618]: Created slice User Core Session Slice.1021machine # [ 15.688553] systemd[618]: Starting D-Bus User Message Bus...1022machine # [ 15.714678] dbus-broker-launch[909]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/y08k2iaf3v8vn0y4c1mn7arhd3jw6srk-system-path/share/dbus-1/services/org.freedesktop.import1.service'1023machine # [ 15.717496] dbus-broker-launch[909]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/y08k2iaf3v8vn0y4c1mn7arhd3jw6srk-system-path/share/dbus-1/services/org.freedesktop.machine1.service'1024machine # [ 15.720154] dbus-broker-launch[909]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/y08k2iaf3v8vn0y4c1mn7arhd3jw6srk-system-path/share/dbus-1/services/org.freedesktop.portable1.service'1025machine # [ 15.722658] dbus-broker-launch[909]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/y08k2iaf3v8vn0y4c1mn7arhd3jw6srk-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'1026machine # [ 15.725393] dbus-broker-launch[909]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored1027machine # [ 15.726893] dbus-broker-launch[909]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored1028machine # [ 15.728890] systemd[618]: Started D-Bus User Message Bus.1029machine # [ 15.733160] dbus-broker-launch[909]: Ready1030machine # [ 15.769876] systemd[618]: Reexecution requested from client PID 908 ('switch-to-confi')...1031machine # [ 15.772581] systemd[618]: Reexecuting.1032machine # [ 15.794118] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1033machine # [ 15.867372] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1034machine # [ 15.872725] systemd[618]: Reload requested from client PID 908 ('switch-to-confi')...1035machine # [ 15.874346] systemd[618]: Reloading...1036machine # [ 15.949955] systemd[618]: Reloading finished in 72 ms.1037machine # [ 15.953847] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1038machine # restarting the following user units: nixos-activation.service1039machine # [ 15.961235] systemd[618]: Stopped Run user-specific NixOS activation.1040machine # [ 15.962140] systemd[618]: Stopping Run user-specific NixOS activation...1041machine # [ 15.964237] systemd[618]: Starting Run user-specific NixOS activation...1042machine # [ 15.984775] systemd[618]: Finished Run user-specific NixOS activation.1043machine # restarting sysinit-reactivation.target1044machine # [ 15.993223] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....1045machine # [ 16.059823] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..1046machine # [ 16.061748] systemd[1]: Reached target Reactivate sysinit units.1047machine # [ 16.066180] systemd[1]: Reached target Local File Systems.1048machine # [ 16.068101] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1049machine # [ 16.070052] systemd[1]: Starting Create SUID/SGID Wrappers...1050machine # [ 16.071848] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1051machine # [ 16.073835] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671052machine # [ 16.077177] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1053machine # [ 16.080115] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1054machine # [ 16.082326] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1055machine # [ 16.083502] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1056machine # [ 16.084730] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1057machine # [ 16.091075] systemd[1]: Reached target Remote File Systems.1058machine # [ 16.217264] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1059machine # [ 16.218928] systemd[1]: Finished Create SUID/SGID Wrappers.1060machine # [ 16.223263] systemd[1]: Starting linger-users.service...1061machine # [ 16.253186] systemd[1]: linger-users.service: Deactivated successfully.1062machine # [ 16.255225] systemd[1]: Finished linger-users.service.1063machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service1064machine # [ 16.515906] nixos[839]: finished switching to system configuration /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test1065machine: (finished: must succeed: /nix/store/3vbcjh6yz1i6lp352svzp92f3jmjppg7-stderr-runner /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/bin/switch-to-configuration test, in 1.54 seconds)1066machine: must succeed: rm /etc/NIXOS1067machine: (finished: must succeed: rm /etc/NIXOS, in 0.03 seconds)1068machine: must succeed: /nix/store/3vbcjh6yz1i6lp352svzp92f3jmjppg7-stderr-runner /nix/store/p86fv0f8pa1jdd0jcv1acymp8xvc1y16-nixos-system-other-test/bin/switch-to-configuration test1069machine # this should succeed (config: /nix/store/p86fv0f8pa1jdd0jcv1acymp8xvc1y16-nixos-system-other-test, action: test)1070machine # Checking switch inhibitors... done1071machine # [ 16.779360] nixos[986]: switching to system configuration /nix/store/p86fv0f8pa1jdd0jcv1acymp8xvc1y16-nixos-system-other-test1072machine # [ 16.781790] systemd[1]: Stopped target Remote File Systems.1073machine # [ 16.783463] systemd[1]: Stopped target Local File Systems.1074machine # activating the configuration...1075machine # removing user ‘usertest’1076machine # setting up /etc...1077machine # [ 17.013880] systemd[1]: Reload requested from client PID 986 ('.switch-to-conf') (unit backdoor.service)...1078machine # [ 17.015521] systemd[1]: Reloading...1079machine # [ 17.261553] systemd[1]: Reloading finished in 244 ms.1080machine # reloading user units for usertest...1081machine # [ 17.334320] systemd[618]: Reexecution requested from client PID 1057 ('switch-to-confi')...1082machine # [ 17.336573] systemd[618]: Reexecuting.1083machine # [ 17.359974] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1084machine # [ 17.439869] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1085machine # [ 17.445987] systemd[618]: Reload requested from client PID 1057 ('switch-to-confi')...1086machine # [ 17.447287] systemd[618]: Reloading...1087machine # [ 17.524185] systemd[618]: Reloading finished in 75 ms.1088machine # [ 17.529840] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1089machine # restarting the following user units: nixos-activation.service1090machine # [ 17.538067] systemd[618]: Stopped Run user-specific NixOS activation.1091machine # [ 17.539025] systemd[618]: Stopping Run user-specific NixOS activation...1092machine # [ 17.540952] systemd[618]: Starting Run user-specific NixOS activation...1093machine # [ 17.561609] systemd[618]: Finished Run user-specific NixOS activation.1094machine # restarting sysinit-reactivation.target1095machine # [ 17.565308] systemd[1]: Stopped target Reactivate sysinit units.1096machine # [ 17.566625] systemd[1]: Stopping Reactivate sysinit units...1097machine # [ 17.567663] systemd[1]: Reached target Reactivate sysinit units.1098machine # reloading the following units: dbus-broker.service1099machine # [ 17.571375] dbus-broker-launch[492]: Caught SIGHUP, trigger reload.1100machine # [ 17.572304] systemd[1]: Reloading D-Bus System Message Bus...1101machine # [ 17.577300] dbus-broker-launch[492]: Looking up NSS user entry for 'systemd-timesync'...1102machine # [ 17.579118] dbus-broker-launch[492]: NSS returned no entry for 'systemd-timesync'1103machine # [ 17.580069] dbus-broker-launch[492]: Invalid user-name in /nix/store/x4zl3lhqy8yqplw3sjgp4mixpgmzbdy2-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1104machine # [ 17.589858] systemd[1]: Reloaded D-Bus System Message Bus.1105machine # restarting the following units: network-addresses-eth1.service1106machine # [ 17.594770] systemd[1]: Stopping Address configuration of eth1...1107machine # [ 17.613808] network-addresses-eth1-pre-stop[1069]: deleting address 192.168.1.1/24... done1108machine # [ 17.619124] network-addresses-eth1-pre-stop[1069]: deleting address 2001:db8:1::1/64... done1109machine # [ 17.624366] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1110machine # [ 17.625910] systemd[1]: Stopped Address configuration of eth1.1111machine # [ 17.629438] systemd[1]: Starting Address configuration of eth1...1112machine # [ 17.657472] network-addresses-eth1-start[1074]: adding address 192.168.1.2/24... done1113machine # [ 17.662860] network-addresses-eth1-start[1074]: adding address 2001:db8:1::2/64... done1114machine # [ 17.670266] systemd[1]: Finished Address configuration of eth1.1115machine # [ 17.674082] systemd[1]: Reached target Remote File Systems.1116machine # [ 17.677466] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1117machine # [ 17.679349] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1118machine # [ 17.682158] systemd[1]: Reached target Local File Systems.1119machine # [ 17.685082] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1120machine # [ 17.690247] systemd[1]: Starting Create SUID/SGID Wrappers...1121machine # [ 17.691079] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1122machine # [ 17.692252] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671123machine # [ 17.694214] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1124machine # [ 17.695634] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1125machine # [ 17.696801] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1126machine # [ 17.851126] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1127machine # [ 17.854175] systemd[1]: Finished Create SUID/SGID Wrappers.1128machine # [ 17.856704] systemd[1]: Starting linger-users.service...1129machine # [ 17.880300] linger-users-start[1146]: id: ‘usertest’: no such user1130machine # [ 17.881828] linger-users-start[1144]: Removing linger for missing user usertest1131machine # [ 17.886756] systemd[1]: linger-users.service: Deactivated successfully.1132machine # [ 17.888321] systemd[1]: Finished linger-users.service.1133machine # [ 18.143516] nixos[986]: finished switching to system configuration /nix/store/p86fv0f8pa1jdd0jcv1acymp8xvc1y16-nixos-system-other-test1134machine: (finished: must succeed: /nix/store/3vbcjh6yz1i6lp352svzp92f3jmjppg7-stderr-runner /nix/store/p86fv0f8pa1jdd0jcv1acymp8xvc1y16-nixos-system-other-test/bin/switch-to-configuration test, in 1.59 seconds)1135subtest: pre-switch checks1136machine: must succeed: /nix/store/3vbcjh6yz1i6lp352svzp92f3jmjppg7-stderr-runner /nix/store/p86fv0f8pa1jdd0jcv1acymp8xvc1y16-nixos-system-other-test/bin/switch-to-configuration check1137machine # this should succeed (config: /nix/store/p86fv0f8pa1jdd0jcv1acymp8xvc1y16-nixos-system-other-test, action: check)1138machine # Checking switch inhibitors... done1139machine: (finished: must succeed: /nix/store/3vbcjh6yz1i6lp352svzp92f3jmjppg7-stderr-runner /nix/store/p86fv0f8pa1jdd0jcv1acymp8xvc1y16-nixos-system-other-test/bin/switch-to-configuration check, in 0.04 seconds)1140machine: must fail: /nix/store/p86fv0f8pa1jdd0jcv1acymp8xvc1y16-nixos-system-other-test/specialisation/failingCheck/bin/switch-to-configuration check 2>&11141machine: (finished: must fail: /nix/store/p86fv0f8pa1jdd0jcv1acymp8xvc1y16-nixos-system-other-test/specialisation/failingCheck/bin/switch-to-configuration check 2>&1, in 0.02 seconds)1142machine: must fail: /nix/store/p86fv0f8pa1jdd0jcv1acymp8xvc1y16-nixos-system-other-test/specialisation/failingMidCheck/bin/switch-to-configuration check 2>&11143machine: (finished: must fail: /nix/store/p86fv0f8pa1jdd0jcv1acymp8xvc1y16-nixos-system-other-test/specialisation/failingMidCheck/bin/switch-to-configuration check 2>&1, in 0.02 seconds)1144(finished: subtest: pre-switch checks, in 0.08 seconds)1145subtest: switch inhibitors1146machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/no_inhibitors/bin/switch-to-configuration switch 2>&11147machine # [ 18.413202] nixos[1175]: switching to system configuration /nix/store/fc1479mg22pnvspa1ll3z0v7hdgqjcs2-nixos-system-machine-test1148machine # [ 18.416674] systemd[1]: Stopped target Remote File Systems.1149machine # [ 18.417713] systemd[1]: Stopped target Local File Systems.1150machine # [ 18.638526] systemd[1]: Reload requested from client PID 1175 ('.switch-to-conf') (unit backdoor.service)...1151machine # [ 18.640174] systemd[1]: Reloading...1152machine # [ 18.931969] systemd[1]: Reloading finished in 289 ms.1153machine # [ 18.997773] systemd[618]: Reexecution requested from client PID 1245 ('switch-to-confi')...1154machine # [ 19.000287] systemd[618]: Reexecuting.1155machine # [ 19.022869] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1156machine # [ 19.096346] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1157machine # [ 19.101892] systemd[618]: Reload requested from client PID 1245 ('switch-to-confi')...1158machine # [ 19.103286] systemd[618]: Reloading...1159machine # [ 19.184615] systemd[618]: Reloading finished in 80 ms.1160machine # [ 19.190855] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1161machine # [ 19.198251] systemd[618]: Stopped Run user-specific NixOS activation.1162machine # [ 19.199158] systemd[618]: Stopping Run user-specific NixOS activation...1163machine # [ 19.201284] systemd[618]: Starting Run user-specific NixOS activation...1164machine # [ 19.222087] systemd[618]: Finished Run user-specific NixOS activation.1165machine # [ 19.225291] systemd[1]: Stopped target Reactivate sysinit units.1166machine # [ 19.226664] systemd[1]: Stopping Reactivate sysinit units...1167machine # [ 19.227744] systemd[1]: Reached target Reactivate sysinit units.1168machine # [ 19.230886] dbus-broker-launch[492]: Caught SIGHUP, trigger reload.1169machine # [ 19.232200] systemd[1]: Reloading D-Bus System Message Bus...1170machine # [ 19.235792] dbus-broker-launch[492]: Looking up NSS user entry for 'systemd-timesync'...1171machine # [ 19.237685] dbus-broker-launch[492]: NSS returned no entry for 'systemd-timesync'1172machine # [ 19.238713] dbus-broker-launch[492]: Invalid user-name in /nix/store/y08k2iaf3v8vn0y4c1mn7arhd3jw6srk-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1173machine # [ 19.246247] systemd[1]: Reloaded D-Bus System Message Bus.1174machine # [ 19.250429] systemd[1]: Stopping Address configuration of eth1...1175machine # [ 19.269486] network-addresses-eth1-pre-stop[1257]: deleting address 192.168.1.2/24... done1176machine # [ 19.274849] network-addresses-eth1-pre-stop[1257]: deleting address 2001:db8:1::2/64... done1177machine # [ 19.282950] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1178machine # [ 19.285055] systemd[1]: Stopped Address configuration of eth1.1179machine # [ 19.288070] systemd[1]: Starting Address configuration of eth1...1180machine # [ 19.318742] network-addresses-eth1-start[1262]: adding address 192.168.1.1/24... done1181machine # [ 19.324415] network-addresses-eth1-start[1262]: adding address 2001:db8:1::1/64... done1182machine # [ 19.331823] systemd[1]: Finished Address configuration of eth1.1183machine # [ 19.335571] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1184machine # [ 19.337409] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1185machine # [ 19.342779] systemd[1]: Reached target Remote File Systems.1186machine # [ 19.344490] systemd[1]: Reached target Local File Systems.1187machine # [ 19.346736] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1188machine # [ 19.350362] systemd[1]: Starting Create SUID/SGID Wrappers...1189machine # [ 19.352251] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1190machine # [ 19.354157] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671191machine # [ 19.356416] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1192machine # [ 19.357985] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1193machine # [ 19.359127] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1194machine # [ 19.490701] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1195machine # [ 19.492439] systemd[1]: Finished Create SUID/SGID Wrappers.1196machine # [ 19.496565] systemd[1]: Starting linger-users.service...1197machine # [ 19.521913] systemd[1]: linger-users.service: Deactivated successfully.1198machine # [ 19.523374] systemd[1]: Finished linger-users.service.1199machine # [ 19.781255] nixos[1175]: finished switching to system configuration /nix/store/fc1479mg22pnvspa1ll3z0v7hdgqjcs2-nixos-system-machine-test1200machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/no_inhibitors/bin/switch-to-configuration switch 2>&1, in 1.57 seconds)1201machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/inhibitors/bin/switch-to-configuration switch 2>&11202machine # [ 20.009293] nixos[1331]: switching to system configuration /nix/store/gk8f4wvkvjir0nvvpzrrc0l9h4kd7lr9-nixos-system-machine-test1203machine # [ 20.011794] systemd[1]: Stopped target Remote File Systems.1204machine # [ 20.013630] systemd[1]: Stopped target Local File Systems.1205machine # [ 20.221231] systemd[1]: Reload requested from client PID 1331 ('.switch-to-conf') (unit backdoor.service)...1206machine # [ 20.222874] systemd[1]: Reloading...1207machine # [ 20.459354] systemd[1]: Reloading finished in 235 ms.1208machine # [ 20.524298] systemd[618]: Reexecution requested from client PID 1401 ('switch-to-confi')...1209machine # [ 20.526444] systemd[618]: Reexecuting.1210machine # [ 20.547142] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1211machine # [ 20.623091] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1212machine # [ 20.628772] systemd[618]: Reload requested from client PID 1401 ('switch-to-confi')...1213machine # [ 20.630316] systemd[618]: Reloading...1214machine # [ 20.705367] systemd[618]: Reloading finished in 73 ms.1215machine # [ 20.709835] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1216machine # [ 20.716247] systemd[618]: Stopped Run user-specific NixOS activation.1217machine # [ 20.717269] systemd[618]: Stopping Run user-specific NixOS activation...1218machine # [ 20.719901] systemd[618]: Starting Run user-specific NixOS activation...1219machine # [ 20.739902] systemd[618]: Finished Run user-specific NixOS activation.1220machine # [ 20.743176] systemd[1]: Stopped target Reactivate sysinit units.1221machine # [ 20.744143] systemd[1]: Stopping Reactivate sysinit units...1222machine # [ 20.745080] systemd[1]: Reached target Reactivate sysinit units.1223machine # [ 20.748467] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1224machine # [ 20.750187] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1225machine # [ 20.754812] systemd[1]: Reached target Remote File Systems.1226machine # [ 20.759140] systemd[1]: Starting linger-users.service...1227machine # [ 20.762143] systemd[1]: Reached target Local File Systems.1228machine # [ 20.768070] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1229machine # [ 20.772300] systemd[1]: Starting Create SUID/SGID Wrappers...1230machine # [ 20.773265] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1231machine # [ 20.774484] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671232machine # [ 20.776562] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1233machine # [ 20.778063] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1234machine # [ 20.779214] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1235machine # [ 20.816143] systemd[1]: linger-users.service: Deactivated successfully.1236machine # [ 20.817924] systemd[1]: Finished linger-users.service.1237machine # [ 20.928652] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1238machine # [ 20.930966] systemd[1]: Finished Create SUID/SGID Wrappers.1239machine # [ 21.189298] nixos[1331]: finished switching to system configuration /nix/store/gk8f4wvkvjir0nvvpzrrc0l9h4kd7lr9-nixos-system-machine-test1240machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/inhibitors/bin/switch-to-configuration switch 2>&1, in 1.41 seconds)1241machine: must fail: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/inhibitors_changed/bin/switch-to-configuration switch 2>&11242machine: (finished: must fail: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/inhibitors_changed/bin/switch-to-configuration switch 2>&1, in 0.05 seconds)1243machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/inhibitors_changed/bin/switch-to-configuration boot 2>&11244machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/inhibitors_changed/bin/switch-to-configuration boot 2>&1, in 0.02 seconds)1245machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/inhibitors_changed/bin/switch-to-configuration dry-activate 2>&11246machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/inhibitors_changed/bin/switch-to-configuration dry-activate 2>&1, in 0.26 seconds)1247machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/inhibitors_new/bin/switch-to-configuration switch 2>&11248machine # [ 21.721945] nixos[1497]: switching to system configuration /nix/store/f6vb05bz4zz0i7asnr6d4v7dkhhhj2pa-nixos-system-machine-test1249machine # [ 21.725607] systemd[1]: Stopped target Local File Systems.1250machine # [ 21.726557] systemd[1]: Stopped target Remote File Systems.1251machine # [ 21.933141] systemd[1]: Reload requested from client PID 1497 ('.switch-to-conf') (unit backdoor.service)...1252machine # [ 21.934941] systemd[1]: Reloading...1253machine # [ 22.170117] systemd[1]: Reloading finished in 233 ms.1254machine # [ 22.233417] systemd[618]: Reexecution requested from client PID 1567 ('switch-to-confi')...1255machine # [ 22.234772] systemd[618]: Reexecuting.1256machine # [ 22.254190] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1257machine # [ 22.332455] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1258machine # [ 22.337662] systemd[618]: Reload requested from client PID 1567 ('switch-to-confi')...1259machine # [ 22.338900] systemd[618]: Reloading...1260machine # [ 22.413595] systemd[618]: Reloading finished in 72 ms.1261machine # [ 22.417849] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1262machine # [ 22.424197] systemd[618]: Stopped Run user-specific NixOS activation.1263machine # [ 22.426061] systemd[618]: Stopping Run user-specific NixOS activation...1264machine # [ 22.427783] systemd[618]: Starting Run user-specific NixOS activation...1265machine # [ 22.447870] systemd[618]: Finished Run user-specific NixOS activation.1266machine # [ 22.451148] systemd[1]: Stopped target Reactivate sysinit units.1267machine # [ 22.451952] systemd[1]: Stopping Reactivate sysinit units...1268machine # [ 22.453463] systemd[1]: Reached target Reactivate sysinit units.1269machine # [ 22.457239] systemd[1]: Reached target Local File Systems.1270machine # [ 22.459164] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1271machine # [ 22.461502] systemd[1]: Starting Create SUID/SGID Wrappers...1272machine # [ 22.463535] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1273machine # [ 22.465539] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671274machine # [ 22.468437] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1275machine # [ 22.470277] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1276machine # [ 22.473244] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1277machine # [ 22.474369] systemd[1]: Reached target Remote File Systems.1278machine # [ 22.480158] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1279machine # [ 22.481647] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1280machine # [ 22.607275] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1281machine # [ 22.608959] systemd[1]: Finished Create SUID/SGID Wrappers.1282machine # [ 22.612955] systemd[1]: Starting linger-users.service...1283machine # [ 22.641899] systemd[1]: linger-users.service: Deactivated successfully.1284machine # [ 22.643435] systemd[1]: Finished linger-users.service.1285machine # [ 22.899694] nixos[1497]: finished switching to system configuration /nix/store/f6vb05bz4zz0i7asnr6d4v7dkhhhj2pa-nixos-system-machine-test1286machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/inhibitors_new/bin/switch-to-configuration switch 2>&1, in 1.37 seconds)1287machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/no_inhibitors/bin/switch-to-configuration switch 2>&11288machine # [ 23.095362] nixos[1638]: switching to system configuration /nix/store/fc1479mg22pnvspa1ll3z0v7hdgqjcs2-nixos-system-machine-test1289machine # [ 23.097783] systemd[1]: Stopped target Local File Systems.1290machine # [ 23.099650] systemd[1]: Stopped target Remote File Systems.1291machine # [ 23.310683] systemd[1]: Reload requested from client PID 1638 ('.switch-to-conf') (unit backdoor.service)...1292machine # [ 23.312497] systemd[1]: Reloading...1293machine # [ 23.547434] systemd[1]: Reloading finished in 233 ms.1294machine # [ 23.610768] systemd[618]: Reexecution requested from client PID 1708 ('switch-to-confi')...1295machine # [ 23.613663] systemd[618]: Reexecuting.1296machine # [ 23.633181] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1297machine # [ 23.706708] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1298machine # [ 23.711898] systemd[618]: Reload requested from client PID 1708 ('switch-to-confi')...1299machine # [ 23.713165] systemd[618]: Reloading...1300machine # [ 23.793213] systemd[618]: Reloading finished in 78 ms.1301machine # [ 23.797876] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1302machine # [ 23.804043] systemd[618]: Stopped Run user-specific NixOS activation.1303machine # [ 23.805054] systemd[618]: Stopping Run user-specific NixOS activation...1304machine # [ 23.807665] systemd[618]: Starting Run user-specific NixOS activation...1305machine # [ 23.828831] systemd[618]: Finished Run user-specific NixOS activation.1306machine # [ 23.831877] systemd[1]: Stopped target Reactivate sysinit units.1307machine # [ 23.833156] systemd[1]: Stopping Reactivate sysinit units...1308machine # [ 23.833976] systemd[1]: Reached target Reactivate sysinit units.1309machine # [ 23.838707] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1310machine # [ 23.840653] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1311machine # [ 23.844920] systemd[1]: Reached target Remote File Systems.1312machine # [ 23.847535] systemd[1]: Starting linger-users.service...1313machine # [ 23.849399] systemd[1]: Reached target Local File Systems.1314machine # [ 23.855359] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1315machine # [ 23.859273] systemd[1]: Starting Create SUID/SGID Wrappers...1316machine # [ 23.860103] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1317machine # [ 23.861314] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671318machine # [ 23.863284] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1319machine # [ 23.864703] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1320machine # [ 23.869140] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1321machine # [ 23.907747] systemd[1]: linger-users.service: Deactivated successfully.1322machine # [ 23.909982] systemd[1]: Finished linger-users.service.1323machine # [ 24.015777] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1324machine # [ 24.017489] systemd[1]: Finished Create SUID/SGID Wrappers.1325machine # [ 24.272230] nixos[1638]: finished switching to system configuration /nix/store/fc1479mg22pnvspa1ll3z0v7hdgqjcs2-nixos-system-machine-test1326machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/no_inhibitors/bin/switch-to-configuration switch 2>&1, in 1.37 seconds)1327(finished: subtest: switch inhibitors, in 6.05 seconds)1328subtest: actions1329machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/modifiedSystemConf/bin/switch-to-configuration boot 2>&11330machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/modifiedSystemConf/bin/switch-to-configuration boot 2>&1, in 0.02 seconds)1331machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/bin/switch-to-configuration switch 2>&11332machine # [ 24.464736] nixos[1786]: switching to system configuration /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test1333machine # [ 24.468869] systemd[1]: Stopped target Local File Systems.1334machine # [ 24.470055] systemd[1]: Stopped target Remote File Systems.1335machine # [ 24.710159] systemd[1]: Reload requested from client PID 1786 ('.switch-to-conf') (unit backdoor.service)...1336machine # [ 24.711811] systemd[1]: Reloading...1337machine # [ 24.950734] systemd[1]: Reloading finished in 236 ms.1338machine # [ 25.011746] systemd[618]: Reexecution requested from client PID 1857 ('switch-to-confi')...1339machine # [ 25.014374] systemd[618]: Reexecuting.1340machine # [ 25.035180] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1341machine # [ 25.111443] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1342machine # [ 25.116833] systemd[618]: Reload requested from client PID 1857 ('switch-to-confi')...1343machine # [ 25.118849] systemd[618]: Reloading...1344machine # [ 25.190261] systemd[618]: Reloading finished in 71 ms.1345machine # [ 25.195861] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1346machine # [ 25.203068] systemd[618]: Stopped Run user-specific NixOS activation.1347machine # [ 25.203927] systemd[618]: Stopping Run user-specific NixOS activation...1348machine # [ 25.205976] systemd[618]: Starting Run user-specific NixOS activation...1349machine # [ 25.227189] systemd[618]: Finished Run user-specific NixOS activation.1350machine # [ 25.230447] systemd[1]: Stopped target Reactivate sysinit units.1351machine # [ 25.231742] systemd[1]: Stopping Reactivate sysinit units...1352machine # [ 25.232797] systemd[1]: Reached target Reactivate sysinit units.1353machine # [ 25.236524] systemd[1]: Reached target Local File Systems.1354machine # [ 25.239155] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1355machine # [ 25.240786] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1356machine # [ 25.242959] systemd[1]: Reached target Remote File Systems.1357machine # [ 25.247110] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1358machine # [ 25.251739] systemd[1]: Starting Create SUID/SGID Wrappers...1359machine # [ 25.252625] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1360machine # [ 25.254475] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671361machine # [ 25.257509] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1362machine # [ 25.259844] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1363machine # [ 25.262269] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1364machine # [ 25.393281] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1365machine # [ 25.394979] systemd[1]: Finished Create SUID/SGID Wrappers.1366machine # [ 25.399541] systemd[1]: Starting linger-users.service...1367machine # [ 25.430362] systemd[1]: linger-users.service: Deactivated successfully.1368machine # [ 25.431864] systemd[1]: Finished linger-users.service.1369machine # [ 25.689664] nixos[1786]: finished switching to system configuration /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test1370machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/bin/switch-to-configuration switch 2>&1, in 1.40 seconds)1371machine: must fail: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/bin/switch-to-configuration broken-action 2>&11372machine: (finished: must fail: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/bin/switch-to-configuration broken-action 2>&1, in 0.03 seconds)1373machine: must fail: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/bin/switch-to-configuration 2>&11374machine: (finished: must fail: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/bin/switch-to-configuration 2>&1, in 0.02 seconds)1375(finished: subtest: actions, in 1.48 seconds)1376subtest: init interface version1377machine: must fail: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/brokenInitInterface/bin/switch-to-configuration test 2>&11378machine: (finished: must fail: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/brokenInitInterface/bin/switch-to-configuration test 2>&1, in 0.03 seconds)1379(finished: subtest: init interface version, in 0.03 seconds)1380subtest: systemd restarts1381machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/modifiedSystemConf/bin/switch-to-configuration test 2>&11382machine # [ 25.981682] nixos[1943]: switching to system configuration /nix/store/0kpjmqlczsqgzwjkr0jvzksw4ng5v6ap-nixos-system-machine-test1383machine # [ 25.985793] systemd[1]: Stopped target Remote File Systems.1384machine # [ 25.986664] systemd[1]: Stopped target Local File Systems.1385machine # [ 26.240056] systemd[1]: Reexecution requested from client PID 1943 ('.switch-to-conf') (unit backdoor.service)...1386machine # [ 26.244061] systemd[1]: Reexecuting.1387machine # [ 26.535386] systemd[1]: systemd 263-0794ba0 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)1388machine # [ 26.543230] systemd[1]: Detected virtualization kvm.1389machine # [ 26.545083] systemd[1]: Detected architecture x86-64.1390machine # [ 26.637010] systemd[1]: bpf-restrict-fs: LSM BPF program attached1391machine # [ 26.732341] systemd[1]: Reload requested from client PID 1943 ('.switch-to-conf') (unit backdoor.service)...1392machine # [ 26.733869] systemd[1]: Reloading...1393machine # [ 26.975773] systemd[1]: Reloading finished in 240 ms.1394machine # [ 27.048832] systemd[618]: Reexecution requested from client PID 2046 ('switch-to-confi')...1395machine # [ 27.050695] systemd[618]: Reexecuting.1396machine # [ 27.071342] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1397machine # [ 27.152256] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1398machine # [ 27.157467] systemd[618]: Reload requested from client PID 2046 ('switch-to-confi')...1399machine # [ 27.158964] systemd[618]: Reloading...1400machine # [ 27.248945] systemd[618]: Reloading finished in 88 ms.1401machine # [ 27.252849] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1402machine # [ 27.259138] systemd[618]: Stopped Run user-specific NixOS activation.1403machine # [ 27.261105] systemd[618]: Stopping Run user-specific NixOS activation...1404machine # [ 27.262830] systemd[618]: Starting Run user-specific NixOS activation...1405machine # [ 27.284030] systemd[618]: Finished Run user-specific NixOS activation.1406machine # [ 27.287511] systemd[1]: Stopped target Reactivate sysinit units.1407machine # [ 27.289175] systemd[1]: Stopping Reactivate sysinit units...1408machine # [ 27.289993] systemd[1]: Reached target Reactivate sysinit units.1409machine # [ 27.292785] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1410machine # [ 27.294525] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1411machine # [ 27.298743] systemd[1]: Reached target Remote File Systems.1412machine # [ 27.301424] systemd[1]: Starting linger-users.service...1413machine # [ 27.305244] systemd[1]: Reached target Local File Systems.1414machine # [ 27.311754] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1415machine # [ 27.318117] systemd[1]: Starting Create SUID/SGID Wrappers...1416machine # [ 27.318903] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1417machine # [ 27.322155] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671418machine # [ 27.324469] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1419machine # [ 27.325887] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1420machine # [ 27.328315] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1421machine # [ 27.367558] systemd[1]: linger-users.service: Deactivated successfully.1422machine # [ 27.369697] systemd[1]: Finished linger-users.service.1423machine # [ 27.487801] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1424machine # [ 27.489492] systemd[1]: Finished Create SUID/SGID Wrappers.1425machine # [ 27.745823] nixos[1943]: finished switching to system configuration /nix/store/0kpjmqlczsqgzwjkr0jvzksw4ng5v6ap-nixos-system-machine-test1426machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/modifiedSystemConf/bin/switch-to-configuration test 2>&1, in 1.97 seconds)1427(finished: subtest: systemd restarts, in 1.97 seconds)1428subtest: continuing from an aborted switch1429machine: must succeed: echo dbus-broker.service > /run/nixos/start-list1430machine: (finished: must succeed: echo dbus-broker.service > /run/nixos/start-list, in 0.02 seconds)1431machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/modifiedSystemConf/bin/switch-to-configuration test 2>&11432machine # [ 27.950053] nixos[2121]: switching to system configuration /nix/store/0kpjmqlczsqgzwjkr0jvzksw4ng5v6ap-nixos-system-machine-test1433machine # [ 27.953496] systemd[1]: Stopped target Local File Systems.1434machine # [ 27.954668] systemd[1]: Stopped target Remote File Systems.1435machine # [ 28.166608] systemd[1]: Reload requested from client PID 2121 ('.switch-to-conf') (unit backdoor.service)...1436machine # [ 28.168474] systemd[1]: Reloading...1437machine # [ 28.409304] systemd[1]: Reloading finished in 238 ms.1438machine # [ 28.474620] systemd[618]: Reexecution requested from client PID 2190 ('switch-to-confi')...1439machine # [ 28.477217] systemd[618]: Reexecuting.1440machine # [ 28.498139] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1441machine # [ 28.571899] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1442machine # [ 28.577141] systemd[618]: Reload requested from client PID 2190 ('switch-to-confi')...1443machine # [ 28.579057] systemd[618]: Reloading...1444machine # [ 28.651981] systemd[618]: Reloading finished in 71 ms.1445machine # [ 28.655848] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1446machine # [ 28.661867] systemd[618]: Stopped Run user-specific NixOS activation.1447machine # [ 28.663355] systemd[618]: Stopping Run user-specific NixOS activation...1448machine # [ 28.665716] systemd[618]: Starting Run user-specific NixOS activation...1449machine # [ 28.687139] systemd[618]: Finished Run user-specific NixOS activation.1450machine # [ 28.690266] systemd[1]: Stopped target Reactivate sysinit units.1451machine # [ 28.691631] systemd[1]: Stopping Reactivate sysinit units...1452machine # [ 28.692687] systemd[1]: Reached target Reactivate sysinit units.1453machine # [ 28.694639] systemd[1]: Reached target Local File Systems.1454machine # [ 28.697240] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1455machine # [ 28.699988] systemd[1]: Starting Create SUID/SGID Wrappers...1456machine # [ 28.701729] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1457machine # [ 28.703531] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671458machine # [ 28.705462] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1459machine # [ 28.706863] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1460machine # [ 28.710171] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1461machine # [ 28.713053] systemd[1]: Reached target Remote File Systems.1462machine # [ 28.719443] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1463machine # [ 28.721193] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1464machine # [ 28.849223] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1465machine # [ 28.850974] systemd[1]: Finished Create SUID/SGID Wrappers.1466machine # [ 28.855153] systemd[1]: Starting linger-users.service...1467machine # [ 28.883383] systemd[1]: linger-users.service: Deactivated successfully.1468machine # [ 28.884829] systemd[1]: Finished linger-users.service.1469machine # [ 29.142475] nixos[2121]: finished switching to system configuration /nix/store/0kpjmqlczsqgzwjkr0jvzksw4ng5v6ap-nixos-system-machine-test1470machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/modifiedSystemConf/bin/switch-to-configuration test 2>&1, in 1.38 seconds)1471(finished: subtest: continuing from an aborted switch, in 1.40 seconds)1472subtest: aborts on already locked lock file1473??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.1474 File "/nix/store/n24r8aim2h0f446mabs0hp8mivzn47d8-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 391475machine # Could not acquire lock1476(finished: subtest: aborts on already locked lock file, in 0.05 seconds)1477subtest: fstab mounts1478machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/bin/switch-to-configuration test 2>&11479machine # [ 29.384993] nixos[2266]: switching to system configuration /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test1480machine # [ 29.387239] systemd[1]: Stopped target Remote File Systems.1481machine # [ 29.388864] systemd[1]: Stopped target Local File Systems.1482machine # [ 29.599547] systemd[1]: Reexecution requested from client PID 2266 ('.switch-to-conf') (unit backdoor.service)...1483machine # [ 29.603475] systemd[1]: Reexecuting.1484machine # [ 29.889232] systemd[1]: systemd 263-0794ba0 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)1485machine # [ 29.893208] systemd[1]: Detected virtualization kvm.1486machine # [ 29.894164] systemd[1]: Detected architecture x86-64.1487machine # [ 29.981629] systemd[1]: bpf-restrict-fs: LSM BPF program attached1488machine # [ 30.076919] systemd[1]: Reload requested from client PID 2266 ('.switch-to-conf') (unit backdoor.service)...1489machine # [ 30.078509] systemd[1]: Reloading...1490machine # [ 30.312916] systemd[1]: Reloading finished in 232 ms.1491machine # [ 30.373398] systemd[618]: Reexecution requested from client PID 2369 ('switch-to-confi')...1492machine # [ 30.375683] systemd[618]: Reexecuting.1493machine # [ 30.396241] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1494machine # [ 30.470308] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1495machine # [ 30.475481] systemd[618]: Reload requested from client PID 2369 ('switch-to-confi')...1496machine # [ 30.476893] systemd[618]: Reloading...1497machine # [ 30.549721] systemd[618]: Reloading finished in 71 ms.1498machine # [ 30.554853] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1499machine # [ 30.562159] systemd[618]: Stopped Run user-specific NixOS activation.1500machine # [ 30.563082] systemd[618]: Stopping Run user-specific NixOS activation...1501machine # [ 30.565235] systemd[618]: Starting Run user-specific NixOS activation...1502machine # [ 30.589131] systemd[618]: Finished Run user-specific NixOS activation.1503machine # [ 30.592529] systemd[1]: Stopped target Reactivate sysinit units.1504machine # [ 30.593833] systemd[1]: Stopping Reactivate sysinit units...1505machine # [ 30.594869] systemd[1]: Reached target Reactivate sysinit units.1506machine # [ 30.600237] systemd[1]: Reached target Remote File Systems.1507machine # [ 30.602616] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1508machine # [ 30.603832] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1509machine # [ 30.606341] systemd[1]: Starting linger-users.service...1510machine # [ 30.608761] systemd[1]: Reached target Local File Systems.1511machine # [ 30.610160] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1512machine # [ 30.615901] systemd[1]: Starting Create SUID/SGID Wrappers...1513machine # [ 30.616747] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1514machine # [ 30.617974] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671515machine # [ 30.620144] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1516machine # [ 30.621550] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1517machine # [ 30.622746] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1518machine # [ 30.669815] systemd[1]: linger-users.service: Deactivated successfully.1519machine # [ 30.671315] systemd[1]: Finished linger-users.service.1520machine # [ 30.776488] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1521machine # [ 30.778255] systemd[1]: Finished Create SUID/SGID Wrappers.1522machine # [ 31.036317] nixos[2266]: finished switching to system configuration /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test1523machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.84 seconds)1524machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/addedMount/bin/switch-to-configuration test 2>&11525machine # [ 31.254989] nixos[2441]: switching to system configuration /nix/store/5fw8yp6lgjzkp9f14pqbzwq1nfck0rna-nixos-system-machine-test1526machine # [ 31.257438] systemd[1]: Stopped target Remote File Systems.1527machine # [ 31.258486] systemd[1]: Stopped target Local File Systems.1528machine # [ 31.492615] systemd[1]: Reload requested from client PID 2441 ('.switch-to-conf') (unit backdoor.service)...1529machine # [ 31.494266] systemd[1]: Reloading...1530machine # [ 31.729971] systemd[1]: Reloading finished in 234 ms.1531machine # [ 31.792377] systemd[618]: Reexecution requested from client PID 2510 ('switch-to-confi')...1532machine # [ 31.794858] systemd[618]: Reexecuting.1533machine # [ 31.818137] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1534machine # [ 31.890753] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1535machine # [ 31.896215] systemd[618]: Reload requested from client PID 2510 ('switch-to-confi')...1536machine # [ 31.897613] systemd[618]: Reloading...1537machine # [ 31.970618] systemd[618]: Reloading finished in 71 ms.1538machine # [ 31.975854] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1539machine # [ 31.981909] systemd[618]: Stopped Run user-specific NixOS activation.1540machine # [ 31.983407] systemd[618]: Stopping Run user-specific NixOS activation...1541machine # [ 31.985641] systemd[618]: Starting Run user-specific NixOS activation...1542machine # [ 32.006441] systemd[618]: Finished Run user-specific NixOS activation.1543machine # [ 32.009565] systemd[1]: Stopped target Reactivate sysinit units.1544machine # [ 32.010926] systemd[1]: Stopping Reactivate sysinit units...1545machine # [ 32.011987] systemd[1]: Reached target Reactivate sysinit units.1546machine # [ 32.014142] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1547machine # [ 32.017838] systemd[1]: Starting Create SUID/SGID Wrappers...1548machine # [ 32.019642] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671549machine # [ 32.022139] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1550machine # [ 32.023848] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1551machine # [ 32.026434] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1552machine # [ 32.027524] systemd[1]: Reached target Remote File Systems.1553machine # [ 32.036994] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1554machine # [ 32.038524] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1555machine # [ 32.162393] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1556machine # [ 32.164891] systemd[1]: Finished Create SUID/SGID Wrappers.1557machine # [ 32.355222] systemd[1]: Mounting /test...1558machine # [ 32.371373] systemd[1]: Mounted /test.1559machine # [ 32.373380] systemd[1]: Reached target Local File Systems.1560machine # [ 32.375082] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1561machine # [ 32.377945] systemd[1]: Starting linger-users.service...1562machine # [ 32.404962] systemd[1]: linger-users.service: Deactivated successfully.1563machine # [ 32.407424] systemd[1]: Finished linger-users.service.1564machine # [ 32.661143] nixos[2441]: finished switching to system configuration /nix/store/5fw8yp6lgjzkp9f14pqbzwq1nfck0rna-nixos-system-machine-test1565machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/addedMount/bin/switch-to-configuration test 2>&1, in 1.62 seconds)1566machine: must succeed: systemctl stop test.mount1567machine # [ 32.701439] systemd[1]: Stopped target Local File Systems.1568machine # [ 32.705238] systemd[1]: Unmounting /test...1569machine # [ 32.735729] systemd[1]: test.mount: Deactivated successfully.1570machine # [ 32.737407] systemd[1]: Unmounted /test.1571machine: (finished: must succeed: systemctl stop test.mount, in 0.08 seconds)1572machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/addedMount/bin/switch-to-configuration test 2>&11573machine # [ 32.924120] nixos[2589]: switching to system configuration /nix/store/5fw8yp6lgjzkp9f14pqbzwq1nfck0rna-nixos-system-machine-test1574machine # [ 32.926370] systemd[1]: Stopped target Remote File Systems.1575machine # [ 33.140332] systemd[1]: Reload requested from client PID 2589 ('.switch-to-conf') (unit backdoor.service)...1576machine # [ 33.141838] systemd[1]: Reloading...1577machine # [ 33.411797] systemd[1]: Reloading finished in 268 ms.1578machine # [ 33.477964] systemd[618]: Reexecution requested from client PID 2658 ('switch-to-confi')...1579machine # [ 33.480522] systemd[618]: Reexecuting.1580machine # [ 33.501611] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1581machine # [ 33.582102] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1582machine # [ 33.587767] systemd[618]: Reload requested from client PID 2658 ('switch-to-confi')...1583machine # [ 33.589174] systemd[618]: Reloading...1584machine # [ 33.661533] systemd[618]: Reloading finished in 71 ms.1585machine # [ 33.667896] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1586machine # [ 33.675125] systemd[618]: Stopped Run user-specific NixOS activation.1587machine # [ 33.676030] systemd[618]: Stopping Run user-specific NixOS activation...1588machine # [ 33.677496] systemd[618]: Starting Run user-specific NixOS activation...1589machine # [ 33.697752] systemd[618]: Finished Run user-specific NixOS activation.1590machine # [ 33.700957] systemd[1]: Stopped target Reactivate sysinit units.1591machine # [ 33.702361] systemd[1]: Stopping Reactivate sysinit units...1592machine # [ 33.703515] systemd[1]: Reached target Reactivate sysinit units.1593machine # [ 33.705801] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1594machine # [ 33.707602] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1595machine # [ 33.713077] systemd[1]: Mounting /test...1596machine # [ 33.713674] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1597machine # [ 33.717518] systemd[1]: Starting Create SUID/SGID Wrappers...1598machine # [ 33.718503] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671599machine # [ 33.721728] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1600machine # [ 33.725107] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1601machine # [ 33.726275] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1602machine # [ 33.733672] systemd[1]: Reached target Remote File Systems.1603machine # [ 33.755560] systemd[1]: Mounted /test.1604machine # [ 33.758466] systemd[1]: Reached target Local File Systems.1605machine # [ 33.759483] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1606machine # [ 33.874088] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1607machine # [ 33.876712] systemd[1]: Finished Create SUID/SGID Wrappers.1608machine # [ 33.879734] systemd[1]: Starting linger-users.service...1609machine # [ 33.912708] systemd[1]: linger-users.service: Deactivated successfully.1610machine # [ 33.914292] systemd[1]: Finished linger-users.service.1611machine # [ 34.168568] nixos[2589]: finished switching to system configuration /nix/store/5fw8yp6lgjzkp9f14pqbzwq1nfck0rna-nixos-system-machine-test1612machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/addedMount/bin/switch-to-configuration test 2>&1, in 1.43 seconds)1613machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/addedMountOptsModified/bin/switch-to-configuration test 2>&11614machine # [ 34.330434] nixos[2732]: switching to system configuration /nix/store/ldqzy8036slkcz42z3vfv7gdjl52dbhk-nixos-system-machine-test1615machine # [ 34.332687] systemd[1]: Stopped target Local File Systems.1616machine # [ 34.334348] systemd[1]: Stopped target Remote File Systems.1617machine # [ 34.549413] systemd[1]: Reload requested from client PID 2732 ('.switch-to-conf') (unit backdoor.service)...1618machine # [ 34.551145] systemd[1]: Reloading...1619machine # [ 34.791311] systemd[1]: Reloading finished in 238 ms.1620machine # [ 34.853963] systemd[618]: Reexecution requested from client PID 2801 ('switch-to-confi')...1621machine # [ 34.856481] systemd[618]: Reexecuting.1622machine # [ 34.878205] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1623machine # [ 34.952356] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1624machine # [ 34.957734] systemd[618]: Reload requested from client PID 2801 ('switch-to-confi')...1625machine # [ 34.959484] systemd[618]: Reloading...1626machine # [ 35.033487] systemd[618]: Reloading finished in 72 ms.1627machine # [ 35.038871] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1628machine # [ 35.045450] systemd[618]: Stopped Run user-specific NixOS activation.1629machine # [ 35.047063] systemd[618]: Stopping Run user-specific NixOS activation...1630machine # [ 35.049026] systemd[618]: Starting Run user-specific NixOS activation...1631machine # [ 35.071262] systemd[618]: Finished Run user-specific NixOS activation.1632machine # [ 35.074595] systemd[1]: Stopped target Reactivate sysinit units.1633machine # [ 35.075644] systemd[1]: Stopping Reactivate sysinit units...1634machine # [ 35.076811] systemd[1]: Reached target Reactivate sysinit units.1635machine # [ 35.079680] systemd[1]: Reloading /test...1636machine # [ 35.097745] systemd[1]: Reloaded /test.1637machine # [ 35.104906] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1638machine # [ 35.106881] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1639machine # [ 35.110270] systemd[1]: Reached target Local File Systems.1640machine # [ 35.111781] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1641machine # [ 35.115258] systemd[1]: Starting Create SUID/SGID Wrappers...1642machine # [ 35.116096] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1643machine # [ 35.117232] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671644machine # [ 35.119173] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1645machine # [ 35.120564] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1646machine # [ 35.121696] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1647machine # [ 35.125490] systemd[1]: Reached target Remote File Systems.1648machine # [ 35.241128] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1649machine # [ 35.243605] systemd[1]: Finished Create SUID/SGID Wrappers.1650machine # [ 35.246974] systemd[1]: Starting linger-users.service...1651machine # [ 35.273909] systemd[1]: linger-users.service: Deactivated successfully.1652machine # [ 35.276174] systemd[1]: Finished linger-users.service.1653machine # [ 35.533476] nixos[2732]: finished switching to system configuration /nix/store/ldqzy8036slkcz42z3vfv7gdjl52dbhk-nixos-system-machine-test1654machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/addedMountOptsModified/bin/switch-to-configuration test 2>&1, in 1.37 seconds)1655machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/addedMountDevModified/bin/switch-to-configuration test 2>&11656machine # [ 35.753652] nixos[2874]: switching to system configuration /nix/store/24y4xg1sns1x24ry5rw5pp66wz0rj1s0-nixos-system-machine-test1657machine # [ 35.756980] systemd[1]: Stopped target Remote File Systems.1658machine # [ 35.758263] systemd[1]: Stopped target Local File Systems.1659machine # [ 36.000885] systemd[1]: Reload requested from client PID 2874 ('.switch-to-conf') (unit backdoor.service)...1660machine # [ 36.002495] systemd[1]: Reloading...1661machine # [ 36.240851] systemd[1]: Reloading finished in 237 ms.1662machine # [ 36.310571] systemd[618]: Reexecution requested from client PID 2943 ('switch-to-confi')...1663machine # [ 36.313231] systemd[618]: Reexecuting.1664machine # [ 36.331272] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1665machine # [ 36.406840] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1666machine # [ 36.412044] systemd[618]: Reload requested from client PID 2943 ('switch-to-confi')...1667machine # [ 36.413877] systemd[618]: Reloading...1668machine # [ 36.486415] systemd[618]: Reloading finished in 71 ms.1669machine # [ 36.491865] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1670machine # [ 36.498385] systemd[618]: Stopped Run user-specific NixOS activation.1671machine # [ 36.500068] systemd[618]: Stopping Run user-specific NixOS activation...1672machine # [ 36.501980] systemd[618]: Starting Run user-specific NixOS activation...1673machine # [ 36.521996] systemd[618]: Finished Run user-specific NixOS activation.1674machine # [ 36.525271] systemd[1]: Stopped target Reactivate sysinit units.1675machine # [ 36.526620] systemd[1]: Stopping Reactivate sysinit units...1676machine # [ 36.527710] systemd[1]: Reached target Reactivate sysinit units.1677machine # [ 36.530200] systemd[1]: Unmounting /test...1678machine # [ 36.544927] systemd[1]: test.mount: Deactivated successfully.1679machine # [ 36.547747] systemd[1]: Unmounted /test.1680machine # [ 36.550669] systemd[1]: Mounting /test...1681machine # [ 36.570255] systemd[1]: Mounted /test.1682machine # [ 36.574592] systemd[1]: Reached target Remote File Systems.1683machine # [ 36.577648] systemd[1]: Starting linger-users.service...1684machine # [ 36.583348] systemd[1]: Reached target Local File Systems.1685machine # [ 36.584693] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1686machine # [ 36.588297] systemd[1]: Starting Create SUID/SGID Wrappers...1687machine # [ 36.589208] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1688machine # [ 36.590405] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671689machine # [ 36.592349] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1690machine # [ 36.596694] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1691machine # [ 36.599218] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1692machine # [ 36.607633] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1693machine # [ 36.609660] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1694machine # [ 36.644315] systemd[1]: linger-users.service: Deactivated successfully.1695machine # [ 36.645958] systemd[1]: Finished linger-users.service.1696machine # [ 36.754420] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1697machine # [ 36.756265] systemd[1]: Finished Create SUID/SGID Wrappers.1698machine # [ 37.012255] nixos[2874]: finished switching to system configuration /nix/store/24y4xg1sns1x24ry5rw5pp66wz0rj1s0-nixos-system-machine-test1699machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/addedMountDevModified/bin/switch-to-configuration test 2>&1, in 1.48 seconds)1700machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/addedMount/bin/switch-to-configuration test 2>&11701machine # [ 37.215153] nixos[3018]: switching to system configuration /nix/store/5fw8yp6lgjzkp9f14pqbzwq1nfck0rna-nixos-system-machine-test1702machine # [ 37.217549] systemd[1]: Stopped target Remote File Systems.1703machine # [ 37.219344] systemd[1]: Stopped target Local File Systems.1704machine # [ 37.464772] systemd[1]: Reload requested from client PID 3018 ('.switch-to-conf') (unit backdoor.service)...1705machine # [ 37.466640] systemd[1]: Reloading...1706machine # [ 37.683449] systemd[1]: Reloading finished in 215 ms.1707machine # [ 37.749301] systemd[618]: Reexecution requested from client PID 3088 ('switch-to-confi')...1708machine # [ 37.751373] systemd[618]: Reexecuting.1709machine # [ 37.773275] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1710machine # [ 37.848902] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1711machine # [ 37.854123] systemd[618]: Reload requested from client PID 3088 ('switch-to-confi')...1712machine # [ 37.855294] systemd[618]: Reloading...1713machine # [ 37.930816] systemd[618]: Reloading finished in 74 ms.1714machine # [ 37.935837] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1715machine # [ 37.941970] systemd[618]: Stopped Run user-specific NixOS activation.1716machine # [ 37.942980] systemd[618]: Stopping Run user-specific NixOS activation...1717machine # [ 37.945478] systemd[618]: Starting Run user-specific NixOS activation...1718machine # [ 37.966358] systemd[618]: Finished Run user-specific NixOS activation.1719machine # [ 37.969724] systemd[1]: Stopped target Reactivate sysinit units.1720machine # [ 37.971564] systemd[1]: Stopping Reactivate sysinit units...1721machine # [ 37.972718] systemd[1]: Reached target Reactivate sysinit units.1722machine # [ 37.975419] systemd[1]: Unmounting /test...1723machine # [ 37.991202] systemd[1]: test.mount: Deactivated successfully.1724machine # [ 37.992612] systemd[1]: Unmounted /test.1725machine # [ 37.995949] systemd[1]: Mounting /test...1726machine # [ 38.012815] systemd[1]: Mounted /test.1727machine # [ 38.015632] systemd[1]: Reached target Remote File Systems.1728machine # [ 38.018193] systemd[1]: Reached target Local File Systems.1729machine # [ 38.020109] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1730machine # [ 38.023561] systemd[1]: Starting Create SUID/SGID Wrappers...1731machine # [ 38.024863] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1732machine # [ 38.026257] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671733machine # [ 38.028588] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1734machine # [ 38.030075] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1735machine # [ 38.035275] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1736machine # [ 38.036548] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1737machine # [ 38.038722] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1738machine # [ 38.166574] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1739machine # [ 38.169052] systemd[1]: Finished Create SUID/SGID Wrappers.1740machine # [ 38.172336] systemd[1]: Starting linger-users.service...1741machine # [ 38.199779] systemd[1]: linger-users.service: Deactivated successfully.1742machine # [ 38.201504] systemd[1]: Finished linger-users.service.1743machine # [ 38.459442] nixos[3018]: finished switching to system configuration /nix/store/5fw8yp6lgjzkp9f14pqbzwq1nfck0rna-nixos-system-machine-test1744machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/addedMount/bin/switch-to-configuration test 2>&1, in 1.45 seconds)1745machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/bin/switch-to-configuration test 2>&11746machine # [ 38.680343] nixos[3163]: switching to system configuration /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test1747machine # [ 38.683114] systemd[1]: Stopped target Local File Systems.1748machine # [ 38.685459] systemd[1]: Unmounting /test...1749machine # [ 38.687795] systemd[1]: Stopped target Remote File Systems.1750machine # [ 38.707352] systemd[1]: test.mount: Deactivated successfully.1751machine # [ 38.708789] systemd[1]: Unmounted /test.1752machine # [ 38.918995] systemd[1]: Reload requested from client PID 3163 ('.switch-to-conf') (unit backdoor.service)...1753machine # [ 38.920893] systemd[1]: Reloading...1754machine # [ 39.155974] systemd[1]: Reloading finished in 234 ms.1755machine # [ 39.217587] systemd[618]: Reexecution requested from client PID 3234 ('switch-to-confi')...1756machine # [ 39.218987] systemd[618]: Reexecuting.1757machine # [ 39.240094] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1758machine # [ 39.312890] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1759machine # [ 39.318021] systemd[618]: Reload requested from client PID 3234 ('switch-to-confi')...1760machine # [ 39.319230] systemd[618]: Reloading...1761machine # [ 39.391984] systemd[618]: Reloading finished in 71 ms.1762machine # [ 39.397877] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1763machine # [ 39.404401] systemd[618]: Stopped Run user-specific NixOS activation.1764machine # [ 39.405459] systemd[618]: Stopping Run user-specific NixOS activation...1765machine # [ 39.408078] systemd[618]: Starting Run user-specific NixOS activation...1766machine # [ 39.429655] systemd[618]: Finished Run user-specific NixOS activation.1767machine # [ 39.433188] systemd[1]: Stopped target Reactivate sysinit units.1768machine # [ 39.434593] systemd[1]: Stopping Reactivate sysinit units...1769machine # [ 39.435582] systemd[1]: Reached target Reactivate sysinit units.1770machine # [ 39.438279] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1771machine # [ 39.439960] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1772machine # [ 39.446589] systemd[1]: Reached target Local File Systems.1773machine # [ 39.448359] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1774machine # [ 39.451057] systemd[1]: Starting Create SUID/SGID Wrappers...1775machine # [ 39.451848] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1776machine # [ 39.454326] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671777machine # [ 39.457501] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1778machine # [ 39.460246] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1779machine # [ 39.462185] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1780machine # [ 39.465396] systemd[1]: Reached target Remote File Systems.1781machine # [ 39.591987] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1782machine # [ 39.595188] systemd[1]: Finished Create SUID/SGID Wrappers.1783machine # [ 39.597968] systemd[1]: Starting linger-users.service...1784machine # [ 39.626716] systemd[1]: linger-users.service: Deactivated successfully.1785machine # [ 39.628265] systemd[1]: Finished linger-users.service.1786machine # [ 39.886323] nixos[3163]: finished switching to system configuration /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test1787machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.43 seconds)1788machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/storeMountModified/bin/switch-to-configuration test 2>&11789machine # [ 40.111855] nixos[3305]: switching to system configuration /nix/store/wyaq5wxc0lcrp72flfrrs00p5gxlsfzh-nixos-system-machine-test1790machine # [ 40.115638] systemd[1]: Stopped target Remote File Systems.1791machine # [ 40.116534] systemd[1]: Stopped target Local File Systems.1792machine # [ 40.344116] systemd[1]: Reload requested from client PID 3305 ('.switch-to-conf') (unit backdoor.service)...1793machine # [ 40.345888] systemd[1]: Reloading...1794machine # [ 40.601247] systemd[1]: Reloading finished in 253 ms.1795machine # [ 40.697631] systemd[618]: Reexecution requested from client PID 3374 ('switch-to-confi')...1796machine # [ 40.699684] systemd[618]: Reexecuting.1797machine # [ 40.722503] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1798machine # [ 40.795487] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1799machine # [ 40.800760] systemd[618]: Reload requested from client PID 3374 ('switch-to-confi')...1800machine # [ 40.802412] systemd[618]: Reloading...1801machine # [ 40.872405] systemd[618]: Reloading finished in 68 ms.1802machine # [ 40.877871] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1803machine # [ 40.884386] systemd[618]: Stopped Run user-specific NixOS activation.1804machine # [ 40.886060] systemd[618]: Stopping Run user-specific NixOS activation...1805machine # [ 40.887994] systemd[618]: Starting Run user-specific NixOS activation...1806machine # [ 40.908324] systemd[618]: Finished Run user-specific NixOS activation.1807machine # [ 40.911506] systemd[1]: Stopped target Reactivate sysinit units.1808machine # [ 40.912940] systemd[1]: Stopping Reactivate sysinit units...1809machine # [ 40.913926] systemd[1]: Reached target Reactivate sysinit units.1810machine # [ 40.917304] systemd[1]: Reached target Local File Systems.1811machine # [ 40.919198] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1812machine # [ 40.923065] systemd[1]: Starting Create SUID/SGID Wrappers...1813machine # [ 40.923828] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1814machine # [ 40.924938] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671815machine # [ 40.926798] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1816machine # [ 40.928201] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1817machine # [ 40.929336] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1818machine # [ 40.934385] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1819machine # [ 40.935616] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1820machine # [ 40.942926] systemd[1]: Reached target Remote File Systems.1821machine # [ 41.072211] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1822machine # [ 41.073885] systemd[1]: Finished Create SUID/SGID Wrappers.1823machine # [ 41.078326] systemd[1]: Starting linger-users.service...1824machine # [ 41.107701] systemd[1]: linger-users.service: Deactivated successfully.1825machine # [ 41.109195] systemd[1]: Finished linger-users.service.1826machine # [ 41.363893] nixos[3305]: finished switching to system configuration /nix/store/wyaq5wxc0lcrp72flfrrs00p5gxlsfzh-nixos-system-machine-test1827machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/storeMountModified/bin/switch-to-configuration test 2>&1, in 1.47 seconds)1828machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/automount/bin/switch-to-configuration test 2>&11829machine # [ 41.546993] nixos[3446]: switching to system configuration /nix/store/2dg6mn7magqsidb42zb14db27padmaya-nixos-system-machine-test1830machine # [ 41.549530] systemd[1]: Stopped target Local File Systems.1831machine # [ 41.551773] systemd[1]: Stopped target Remote File Systems.1832machine # [ 41.809833] systemd[1]: Reload requested from client PID 3446 ('.switch-to-conf') (unit backdoor.service)...1833machine # [ 41.811506] systemd[1]: Reloading...1834machine # [ 42.053729] systemd[1]: Reloading finished in 241 ms.1835machine # [ 42.124731] systemd[618]: Reexecution requested from client PID 3515 ('switch-to-confi')...1836machine # [ 42.127482] systemd[618]: Reexecuting.1837machine # [ 42.149434] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1838machine # [ 42.226296] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1839machine # [ 42.231711] systemd[618]: Reload requested from client PID 3515 ('switch-to-confi')...1840machine # [ 42.234142] systemd[618]: Reloading...1841machine # [ 42.310802] systemd[618]: Reloading finished in 75 ms.1842machine # [ 42.314882] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1843machine # [ 42.321828] systemd[618]: Stopped Run user-specific NixOS activation.1844machine # [ 42.322857] systemd[618]: Stopping Run user-specific NixOS activation...1845machine # [ 42.325504] systemd[618]: Starting Run user-specific NixOS activation...1846machine # [ 42.347955] systemd[618]: Finished Run user-specific NixOS activation.1847machine # [ 42.351292] systemd[1]: Stopped target Reactivate sysinit units.1848machine # [ 42.352699] systemd[1]: Stopping Reactivate sysinit units...1849machine # [ 42.353702] systemd[1]: Reached target Reactivate sysinit units.1850machine # [ 42.355664] systemd[1]: Set up automount testauto.automount.1851machine # [ 42.358270] systemd[1]: Reached target Local File Systems.1852machine # [ 42.360520] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1853machine # [ 42.362196] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1854machine # [ 42.365053] systemd[1]: Reached target Remote File Systems.1855machine # [ 42.367688] systemd[1]: Starting linger-users.service...1856machine # [ 42.374183] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1857machine # [ 42.377247] systemd[1]: Starting Create SUID/SGID Wrappers...1858machine # [ 42.378091] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1859machine # [ 42.379225] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671860machine # [ 42.381190] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1861machine # [ 42.382590] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1862machine # [ 42.383734] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1863machine # [ 42.431806] systemd[1]: linger-users.service: Deactivated successfully.1864machine # [ 42.434852] systemd[1]: Finished linger-users.service.1865machine # [ 42.534715] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1866machine # [ 42.536436] systemd[1]: Finished Create SUID/SGID Wrappers.1867machine # [ 42.791396] nixos[3446]: finished switching to system configuration /nix/store/2dg6mn7magqsidb42zb14db27padmaya-nixos-system-machine-test1868machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/automount/bin/switch-to-configuration test 2>&1, in 1.43 seconds)1869machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/bin/switch-to-configuration test 2>&11870machine # [ 42.945114] nixos[3586]: switching to system configuration /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test1871machine # [ 42.947721] systemd[1]: Stopped target Remote File Systems.1872machine # [ 42.949456] systemd[1]: Stopped target Local File Systems.1873machine # [ 42.951104] systemd[1]: testauto.automount: Deactivated successfully.1874machine # [ 42.954144] systemd[1]: Unset automount testauto.automount.1875machine # [ 43.162432] systemd[1]: Reload requested from client PID 3586 ('.switch-to-conf') (unit backdoor.service)...1876machine # [ 43.164103] systemd[1]: Reloading...1877machine # [ 43.403106] systemd[1]: Reloading finished in 237 ms.1878machine # [ 43.467578] systemd[618]: Reexecution requested from client PID 3655 ('switch-to-confi')...1879machine # [ 43.469439] systemd[618]: Reexecuting.1880machine # [ 43.490159] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1881machine # [ 43.563864] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1882machine # [ 43.569054] systemd[618]: Reload requested from client PID 3655 ('switch-to-confi')...1883machine # [ 43.570333] systemd[618]: Reloading...1884machine # [ 43.643469] systemd[618]: Reloading finished in 71 ms.1885machine # [ 43.648876] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1886machine # [ 43.656070] systemd[618]: Stopped Run user-specific NixOS activation.1887machine # [ 43.656914] systemd[618]: Stopping Run user-specific NixOS activation...1888machine # [ 43.658779] systemd[618]: Starting Run user-specific NixOS activation...1889machine # [ 43.679068] systemd[618]: Finished Run user-specific NixOS activation.1890machine # [ 43.682308] systemd[1]: Stopped target Reactivate sysinit units.1891machine # [ 43.683368] systemd[1]: Stopping Reactivate sysinit units...1892machine # [ 43.684580] systemd[1]: Reached target Reactivate sysinit units.1893machine # [ 43.687546] systemd[1]: Reached target Local File Systems.1894machine # [ 43.689206] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1895machine # [ 43.692081] systemd[1]: Starting Create SUID/SGID Wrappers...1896machine # [ 43.692807] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1897machine # [ 43.695382] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671898machine # [ 43.698633] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1899machine # [ 43.700807] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1900machine # [ 43.703117] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1901machine # [ 43.705063] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1902machine # [ 43.706415] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1903machine # [ 43.714760] systemd[1]: Reached target Remote File Systems.1904machine # [ 43.841724] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1905machine # [ 43.843455] systemd[1]: Finished Create SUID/SGID Wrappers.1906machine # [ 43.847633] systemd[1]: Starting linger-users.service...1907machine # [ 43.876206] systemd[1]: linger-users.service: Deactivated successfully.1908machine # [ 43.877679] systemd[1]: Finished linger-users.service.1909machine # [ 44.132070] nixos[3586]: finished switching to system configuration /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test1910machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.34 seconds)1911(finished: subtest: fstab mounts, in 14.93 seconds)1912subtest: swaps1913machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/swap/bin/switch-to-configuration test 2>&11914machine # [ 44.335372] nixos[3726]: switching to system configuration /nix/store/1nr7b1ix4nhh3l5wvr3lhcpnm7pydfff-nixos-system-machine-test1915machine # [ 44.338440] systemd[1]: Stopped target Remote File Systems.1916machine # [ 44.339924] systemd[1]: Stopped target Local File Systems.1917machine # [ 44.566142] systemd[1]: Reload requested from client PID 3726 ('.switch-to-conf') (unit backdoor.service)...1918machine # [ 44.567862] systemd[1]: Reloading...1919machine # [ 44.818218] systemd[1]: Reloading finished in 249 ms.1920machine # [ 44.879311] systemd[618]: Reexecution requested from client PID 3795 ('switch-to-confi')...1921machine # [ 44.881721] systemd[618]: Reexecuting.1922machine # [ 44.903180] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1923machine # [ 44.974949] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1924machine # [ 44.981058] systemd[618]: Reload requested from client PID 3795 ('switch-to-confi')...1925machine # [ 44.982663] systemd[618]: Reloading...1926machine # [ 45.054910] systemd[618]: Reloading finished in 71 ms.1927machine # [ 45.060843] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1928machine # [ 45.067174] systemd[618]: Stopped Run user-specific NixOS activation.1929machine # [ 45.069155] systemd[618]: Stopping Run user-specific NixOS activation...1930machine # [ 45.070743] systemd[618]: Starting Run user-specific NixOS activation...1931machine # [ 45.090879] systemd[618]: Finished Run user-specific NixOS activation.1932machine # [ 45.094148] systemd[1]: Stopped target Reactivate sysinit units.1933machine # [ 45.095741] systemd[1]: Stopping Reactivate sysinit units...1934machine # [ 45.096948] systemd[1]: Reached target Reactivate sysinit units.1935machine # [ 45.100673] systemd[1]: Reached target Local File Systems.1936machine # [ 45.102272] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1937machine # [ 45.106327] systemd[1]: Starting Create SUID/SGID Wrappers...1938machine # [ 45.107158] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1939machine # [ 45.108344] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671940machine # [ 45.111091] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1941machine # [ 45.112548] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1942machine # [ 45.113774] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1943machine # [ 45.118884] systemd[1]: Starting Initialisation of swap device /swapfile...1944machine # [ 45.121359] systemd[1]: Reached target Remote File Systems.1945machine # [ 45.128202] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1946machine # [ 45.130470] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1947machine # [ 45.177751] mkswap-swapfile-start[3808]: Creating swap file using dd and mkswap.1948machine # [ 45.194694] mkswap-swapfile-start[3827]: 1+0 records in1949machine # [ 45.196068] mkswap-swapfile-start[3827]: 1+0 records out1950machine # [ 45.196776] mkswap-swapfile-start[3827]: 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.00267744 s, 392 MB/s1951machine # [ 45.210204] mkswap-swapfile-start[3830]: Setting up swapspace version 1, size = 1020 KiB (1044480 bytes)1952machine # [ 45.211996] mkswap-swapfile-start[3830]: no label, UUID=2e80d09c-0c3c-4275-b854-32b833a099111953machine # [ 45.214772] systemd[1]: mkswap-swapfile.service: Deactivated successfully.1954machine # [ 45.217166] systemd[1]: Finished Initialisation of swap device /swapfile.1955machine # [ 45.220228] systemd[1]: mkswap-swapfile.service: Consumed 28ms CPU time over 97ms wall clock time, 3.3M memory peak, 1M written to disk.1956machine # [ 45.224167] systemd[1]: Activating swap /swapfile...1957machine # [ 45.390769] Adding 1020k swap on /swapfile. Priority:-2 extents:1 across:1020k1958machine # [ 45.251381] systemd[1]: Activated swap /swapfile.1959machine # [ 45.316246] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1960machine # [ 45.318592] systemd[1]: Finished Create SUID/SGID Wrappers.1961machine # [ 45.321874] systemd[1]: Starting linger-users.service...1962machine # [ 45.350255] systemd[1]: linger-users.service: Deactivated successfully.1963machine # [ 45.351728] systemd[1]: Finished linger-users.service.1964machine # [ 45.608656] nixos[3726]: finished switching to system configuration /nix/store/1nr7b1ix4nhh3l5wvr3lhcpnm7pydfff-nixos-system-machine-test1965machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/specialisation/swap/bin/switch-to-configuration test 2>&1, in 1.48 seconds)1966machine: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/bin/switch-to-configuration test 2>&11967machine # [ 45.825569] systemd[1]: swapfile.swap: Deactivated successfully.1968machine # [ 45.827344] nixos[3880]: switching to system configuration /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test1969machine # [ 45.829979] systemd[1]: Stopped target Remote File Systems.1970machine # [ 45.831343] systemd[1]: Stopped target Local File Systems.1971machine # [ 46.048954] systemd[1]: Reload requested from client PID 3880 ('.switch-to-conf') (unit backdoor.service)...1972machine # [ 46.050779] systemd[1]: Reloading...1973machine # [ 46.299310] systemd[1]: Reloading finished in 246 ms.1974machine # [ 46.363761] systemd[618]: Reexecution requested from client PID 3949 ('switch-to-confi')...1975machine # [ 46.365831] systemd[618]: Reexecuting.1976machine # [ 46.388313] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1977machine # [ 46.470772] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1978machine # [ 46.476322] systemd[618]: Reload requested from client PID 3949 ('switch-to-confi')...1979machine # [ 46.477842] systemd[618]: Reloading...1980machine # [ 46.558664] systemd[618]: Reloading finished in 79 ms.1981machine # [ 46.563905] systemd[618]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1982machine # [ 46.570480] systemd[618]: Stopped Run user-specific NixOS activation.1983machine # [ 46.571592] systemd[618]: Stopping Run user-specific NixOS activation...1984machine # [ 46.574262] systemd[618]: Starting Run user-specific NixOS activation...1985machine # [ 46.596666] systemd[618]: Finished Run user-specific NixOS activation.1986machine # [ 46.600374] systemd[1]: Stopped target Reactivate sysinit units.1987machine # [ 46.602156] systemd[1]: Stopping Reactivate sysinit units...1988machine # [ 46.602970] systemd[1]: Reached target Reactivate sysinit units.1989machine # [ 46.606922] systemd[1]: Reached target Local File Systems.1990machine # [ 46.608290] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1991machine # [ 46.612840] systemd[1]: Starting Create SUID/SGID Wrappers...1992machine # [ 46.613709] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1993machine # [ 46.614886] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671994machine # [ 46.616806] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1995machine # [ 46.621297] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1996machine # [ 46.622568] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1997machine # [ 46.627318] systemd[1]: Reached target Remote File Systems.1998machine # [ 46.632472] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1999machine # [ 46.633767] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2000machine # [ 46.768253] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2001machine # [ 46.770315] systemd[1]: Finished Create SUID/SGID Wrappers.2002machine # [ 46.774980] systemd[1]: Starting linger-users.service...2003machine # [ 46.807403] systemd[1]: linger-users.service: Deactivated successfully.2004machine # [ 46.809254] systemd[1]: Finished linger-users.service.2005machine # [ 47.067370] nixos[3880]: finished switching to system configuration /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test2006machine: (finished: must succeed: /nix/store/l5f32h6kp52yjmyyxm5ag23mcygf9f3y-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.46 seconds)2007(finished: subtest: swaps, in 2.94 seconds)2008(finished: run the VM test script, in 48.11 seconds)2009test script finished in 48.20s2010cleanup2011kill QemuMachine (pid 45)2012machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/lb41b0anx1f98y9y5s9mdv97gjgsq740-python3-3.14.7/bin/python3.14)2013machine # [2026-10-03T03:33:14Z INFO virtiofsd] Client disconnected, shutting down2014machine # [2026-10-03T03:33:14Z INFO virtiofsd] Client disconnected, shutting down2015machine # [2026-10-03T03:33:14Z INFO virtiofsd] Client disconnected, shutting down2016(finished: cleanup, in 0.22 seconds)2017additionally exposed symbols:2018 machine, other,2019 vlan1,2020 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