tribuchet: building on jamie Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script machine: waiting for unit multi-user.target machine: waiting for the VM to finish booting machine: starting vm machine: QEMU running (pid 45) machine # Disk image does not exist, creating the virtualisation disk image... machine # Formatting '/build/vm-state-machine/tmp.szlLwSlSiO', fmt=raw size=1073741824 machine # mke2fs 1.47.4 (6-Mar-2025) machine # Discarding device blocks: 0/262144 done machine # Creating filesystem with 262144 4k blocks and 65536 inodes machine # Filesystem UUID: dafbb668-457e-45fe-afd3-b0e08892c282 machine # Superblock backups stored on blocks: machine # 32768, 98304, 163840, 229376 machine # machine # Allocating group tables: 0/8 done machine # Writing inode tables: 0/8 done machine # Creating journal (8192 blocks): done machine # Writing superblocks and filesystem accounting information: 0/8 done machine # machine # Virtualisation disk image created. machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) machine # machine # machine # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA00 machine # Press Ctrl-B to configure iPXE (PCI 00:02.0)... machine # machine # machine # machine # machine # iPXE (http://ipxe.org) 00:08.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB00 machine # Press Ctrl-B to configure iPXE (PCI 00:08.0)... machine # machine # machine # Booting from ROM... machine # Probing EDD (edd=off to disable)... o[ 0.000000] Linux version 6.18.50 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Mon Sep 7 15:23:00 UTC 2026 machine # [ 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/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/init regInfo=/nix/store/a7m1sw049qbcs1011liswdry32dqz2cl-closure-info/registration console=ttyS0,115200n8 console=tty0 machine # [ 0.000000] BIOS-provided physical RAM map: machine # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable machine # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable machine # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved machine # [ 0.000000] NX (Execute Disable) protection: active machine # [ 0.000000] APIC: Static calls initialized machine # [ 0.000000] SMBIOS 2.8 present. machine # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014 machine # [ 0.000000] DMI: Memory slots populated: 1/1 machine # [ 0.000000] Hypervisor detected: KVM machine # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 machine # [ 0.000000] kvm-clock: using sched offset of 498214206 cycles machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns machine # [ 0.000005] tsc: Detected 2400.010 MHz processor machine # [ 0.000811] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 machine # [ 0.000848] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs machine # [ 0.000851] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT machine # [ 0.002766] found SMP MP-table at [mem 0x000f5450-0x000f545f] machine # [ 0.002778] Using GB pages for direct mapping machine # [ 0.002872] RAMDISK: [mem 0x3e425000-0x3ffcffff] machine # [ 0.002880] ACPI: Early table checksum verification disabled machine # [ 0.002883] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) machine # [ 0.002887] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002891] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002897] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002899] ACPI: FACS 0x000000003FFE0000 000040 machine # [ 0.002900] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002902] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002904] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002905] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002906] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d] machine # [ 0.002907] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219] machine # [ 0.002908] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f] machine # [ 0.002908] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385] machine # [ 0.002909] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd] machine # [ 0.002909] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9] machine # [ 0.002910] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421] machine # [ 0.003397] No NUMA configuration found machine # [ 0.003399] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff] machine # [ 0.003402] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff] machine # [ 0.005809] Zone ranges: machine # [ 0.005810] DMA [mem 0x0000000000001000-0x0000000000ffffff] machine # [ 0.005811] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff] machine # [ 0.005813] Normal empty machine # [ 0.005814] Device empty machine # [ 0.005814] Movable zone start for each node machine # [ 0.005815] Early memory node ranges machine # [ 0.005815] node 0: [mem 0x0000000000001000-0x000000000009efff] machine # [ 0.005816] node 0: [mem 0x0000000000100000-0x000000003ffd7fff] machine # [ 0.005817] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff] machine # [ 0.005838] On node 0, zone DMA: 1 pages in unavailable ranges machine # [ 0.006099] On node 0, zone DMA: 97 pages in unavailable ranges machine # [ 0.023993] On node 0, zone DMA32: 40 pages in unavailable ranges machine # [ 0.025112] ACPI: PM-Timer IO Port: 0x608 machine # [ 0.025125] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) machine # [ 0.025154] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 machine # [ 0.025157] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) machine # [ 0.025158] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) machine # [ 0.025160] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) machine # [ 0.025160] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) machine # [ 0.025161] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) machine # [ 0.025163] ACPI: Using ACPI (MADT) for SMP configuration information machine # [ 0.025164] ACPI: HPET id: 0x8086a201 base: 0xfed00000 machine # [ 0.025169] TSC deadline timer available machine # [ 0.025173] CPU topo: Max. logical packages: 1 machine # [ 0.025173] CPU topo: Max. logical dies: 1 machine # [ 0.025174] CPU topo: Max. dies per package: 1 machine # [ 0.025177] CPU topo: Max. threads per core: 1 machine # [ 0.025178] CPU topo: Num. cores per package: 1 machine # [ 0.025178] CPU topo: Num. threads per package: 1 machine # [ 0.025178] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs machine # [ 0.025196] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() machine # [ 0.025235] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] machine # [ 0.025237] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] machine # [ 0.025238] [mem 0x40000000-0xafffffff] available for PCI devices machine # [ 0.025240] Booting paravirtualized kernel on KVM machine # [ 0.025243] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns machine # [ 0.029772] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 machine # [ 0.031971] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152 machine # [ 0.032016] kvm-guest: PV spinlocks disabled, single CPU machine # [ 0.032017] 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/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/init regInfo=/nix/store/a7m1sw049qbcs1011liswdry32dqz2cl-closure-info/registration console=ttyS0,115200n8 console=tty0 machine # [ 0.032106] Unknown kernel command line parameters "regInfo=/nix/store/a7m1sw049qbcs1011liswdry32dqz2cl-closure-info/registration", will be passed to user space. machine # [ 0.032119] random: crng init done machine # [ 0.032119] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes machine # [ 0.032140] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.032153] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.032184] Fallback order for Node 0: 0 machine # [ 0.032187] Built 1 zonelists, mobility grouping on. Total pages: 262006 machine # [ 0.032188] Policy zone: DMA32 machine # [ 0.034673] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.036958] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 machine # [ 0.039377] allocated 2097152 bytes of page_ext machine # [ 0.048898] ftrace: allocating 48731 entries in 192 pages machine # [ 0.048900] ftrace: allocated 192 pages with 2 groups machine # [ 0.049799] Dynamic Preempt: lazy machine # [ 0.049961] rcu: Preemptible hierarchical RCU implementation. machine # [ 0.049962] rcu: RCU event tracing is enabled. machine # [ 0.049962] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. machine # [ 0.049964] Trampoline variant of Tasks RCU enabled. machine # [ 0.049964] Rude variant of Tasks RCU enabled. machine # [ 0.049964] Tracing variant of Tasks RCU enabled. machine # [ 0.049965] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. machine # [ 0.049966] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 machine # [ 0.049987] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.049988] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.049989] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.054324] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16 machine # [ 0.054602] rcu: srcu_init: Setting srcu_struct sizes based on contention. machine # [ 0.054608] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns machine # [ 0.054705] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.062104] Console: colour VGA+ 80x25 machine # [ 0.062108] printk: legacy console [tty0] enabled machine # [ 0.105445] printk: legacy console [ttyS0] enabled machine # [ 0.306442] ACPI: Core revision 20250807 machine # [ 0.308033] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns machine # [ 0.310989] APIC: Switch to symmetric I/O mode setup machine # [ 0.312746] x2apic enabled machine # [ 0.313994] APIC: Switched APIC routing to: physical x2apic machine # [ 0.316993] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 machine # [ 0.318831] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns machine # [ 0.322048] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400010) machine # [ 0.323180] x86/cpu: User Mode Instruction Prevention (UMIP) activated machine # [ 0.325196] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 machine # [ 0.327046] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 machine # [ 0.329051] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto machine # [ 0.331047] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl machine # [ 0.333047] Transient Scheduler Attacks: Mitigation: Clear CPU buffers machine # [ 0.334046] Spectre V2 : Mitigation: Enhanced / Automatic IBRS machine # [ 0.335046] Speculative Return Stack Overflow: Mitigation: Safe RET machine # [ 0.336046] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization machine # [ 0.338055] Spectre V2 : Enabling IBPB for BPF machine # [ 0.339048] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier machine # [ 0.341048] active return thunk: srso_alias_return_thunk machine # [ 0.342075] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' machine # [ 0.344046] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' machine # [ 0.345046] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' machine # [ 0.346046] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' machine # [ 0.348046] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' machine # [ 0.350046] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' machine # [ 0.352046] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' machine # [ 0.354046] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers' machine # [ 0.355046] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)' machine # [ 0.356047] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 machine # [ 0.357046] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 machine # [ 0.359046] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 machine # [ 0.361046] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 machine # [ 0.362046] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 machine # [ 0.363046] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16 machine # [ 0.364046] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24 machine # [ 0.365046] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format. machine # [ 0.399505] Freeing SMP alternatives memory: 44K machine # [ 0.400049] pid_max: default: 32768 minimum: 301 machine # [ 0.402144] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.403158] landlock: Up and running. machine # [ 0.404047] Yama: becoming mindful. machine # [ 0.406211] LSM support for eBPF active machine # [ 0.407145] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.408071] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.411156] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1) machine # [ 0.412626] Performance Events: Fam17h+ core perfctr, AMD PMU driver. machine # [ 0.413056] ... version: 2 machine # [ 0.414048] ... bit width: 48 machine # [ 0.415048] ... generic counters: 6 machine # [ 0.416048] ... generic bitmap: 000000000000003f machine # [ 0.417048] ... fixed-purpose counters: 0 machine # [ 0.418048] ... fixed-purpose bitmap: 0000000000000000 machine # [ 0.419048] ... value mask: 0000ffffffffffff machine # [ 0.420048] ... max period: 00007fffffffffff machine # [ 0.421048] ... global_ctrl mask: 000000000000003f machine # [ 0.422150] signal: max sigframe size: 3376 machine # [ 0.423299] rcu: Hierarchical SRCU implementation. machine # [ 0.424053] rcu: Max phase no-delay instances is 400. machine # [ 0.429672] smp: Bringing up secondary CPUs ... machine # [ 0.430068] smp: Brought up 1 node, 1 CPU machine # [ 0.431051] smpboot: Total of 1 processors activated (4800.02 BogoMIPS) machine # [ 0.432262] Memory: 941788K/1048024K available (17223K kernel code, 2726K rwdata, 13584K rodata, 3644K init, 2988K bss, 98856K reserved, 0K cma-reserved) machine # [ 0.433316] devtmpfs: initialized machine # [ 0.434257] x86/mm: Memory block size: 128MB machine # [ 0.436112] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.437079] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.438150] pinctrl core: initialized pinctrl subsystem machine # [ 0.439384] PM: RTC time: 02:41:49, date: 2026-09-11 machine # [ 0.442894] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.444433] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.445070] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.446203] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.447061] audit: initializing netlink subsys (disabled) machine # [ 0.448370] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.448373] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.449049] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.450052] audit: type=2000 audit(1789094509.656:1): state=initialized audit_enabled=0 res=1 machine # [ 0.452051] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.452053] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.453066] cpuidle: using governor menu machine # [ 0.456243] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 machine # [ 0.457331] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] machine # [ 0.458051] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry machine # [ 0.459061] PCI: Using configuration type 1 for base access machine # [ 0.460290] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. machine # [ 0.465315] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.466049] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.471049] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.472048] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.482618] ACPI: Added _OSI(Module Device) machine # [ 0.483049] ACPI: Added _OSI(Processor Device) machine # [ 0.488048] ACPI: Added _OSI(Processor Aggregator Device) machine # [ 0.493660] ACPI: 1 ACPI AML tables successfully acquired and loaded machine # [ 0.497213] ACPI: Interpreter enabled machine # [ 0.498078] ACPI: PM: (supports S0 S3 S4 S5) machine # [ 0.501049] ACPI: Using IOAPIC for interrupt routing machine # [ 0.502116] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug machine # [ 0.505047] PCI: Using E820 reservations for host bridge windows machine # [ 0.506203] ACPI: Enabled 2 GPEs in block 00 to 3F machine # [ 0.514910] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) machine # [ 0.516101] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] machine # [ 0.517135] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] machine # [ 0.518192] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] machine # [ 0.519732] PCI host bridge to bus 0000:00 machine # [ 0.520055] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] machine # [ 0.521050] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] machine # [ 0.522049] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] machine # [ 0.523056] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window] machine # [ 0.524050] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] machine # [ 0.525049] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] machine # [ 0.526050] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.527216] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.528996] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint machine # [ 0.532129] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] machine # [ 0.533076] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] machine # [ 0.534113] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] machine # [ 0.535279] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] machine # [ 0.537078] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.540063] pci 0000:00:02.0: BAR 0 [io 0xc140-0xc15f] machine # [ 0.541063] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] machine # [ 0.542116] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] machine # [ 0.543063] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] machine # [ 0.545268] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.548063] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f] machine # [ 0.549063] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] machine # [ 0.550102] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] machine # [ 0.552345] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.555063] pci 0000:00:04.0: BAR 0 [io 0xc080-0xc0bf] machine # [ 0.556063] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] machine # [ 0.557103] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] machine # [ 0.559431] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.561063] pci 0000:00:05.0: BAR 0 [io 0xc180-0xc19f] machine # [ 0.562063] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] machine # [ 0.563103] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref] machine # [ 0.565425] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.567093] pci 0000:00:06.0: BAR 0 [io 0xc1a0-0xc1bf] machine # [ 0.568063] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] machine # [ 0.569101] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref] machine # [ 0.571340] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.573665] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f] machine # [ 0.574068] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] machine # [ 0.575102] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref] machine # [ 0.577373] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.579527] pci 0000:00:08.0: BAR 0 [io 0xc1c0-0xc1df] machine # [ 0.580063] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] machine # [ 0.581102] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref] machine # [ 0.582063] pci 0000:00:08.0: ROM [mem 0xfeb80000-0xfebbffff pref] machine # [ 0.584277] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.586094] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] machine # [ 0.587102] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref] machine # [ 0.589287] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.591737] pci 0000:00:0a.0: BAR 0 [io 0xc0c0-0xc0ff] machine # [ 0.593063] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] machine # [ 0.594103] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref] machine # [ 0.596285] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.598076] pci 0000:00:0b.0: BAR 0 [io 0xc1e0-0xc1ff] machine # [ 0.599063] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff] machine # [ 0.600102] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref] machine # [ 0.602397] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.605116] pci 0000:00:1d.0: BAR 4 [io 0xc200-0xc21f] machine # [ 0.606449] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.608766] pci 0000:00:1d.1: BAR 4 [io 0xc220-0xc23f] machine # [ 0.610418] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.612088] pci 0000:00:1d.2: BAR 4 [io 0xc240-0xc25f] machine # [ 0.613649] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.616063] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff] machine # [ 0.617512] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint machine # [ 0.618743] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO machine # [ 0.620805] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint machine # [ 0.623476] pci 0000:00:1f.2: BAR 4 [io 0xc260-0xc27f] machine # [ 0.624063] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff] machine # [ 0.625702] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint machine # [ 0.628115] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] machine # [ 0.635628] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 machine # [ 0.636207] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 machine # [ 0.637208] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 machine # [ 0.638207] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 machine # [ 0.639202] ACPI: PCI: Interrupt link LNKE configured for IRQ 10 machine # [ 0.640200] ACPI: PCI: Interrupt link LNKF configured for IRQ 10 machine # [ 0.641241] ACPI: PCI: Interrupt link LNKG configured for IRQ 11 machine # [ 0.642221] ACPI: PCI: Interrupt link LNKH configured for IRQ 11 machine # [ 0.643112] ACPI: PCI: Interrupt link GSIA configured for IRQ 16 machine # [ 0.644071] ACPI: PCI: Interrupt link GSIB configured for IRQ 17 machine # [ 0.645069] ACPI: PCI: Interrupt link GSIC configured for IRQ 18 machine # [ 0.646066] ACPI: PCI: Interrupt link GSID configured for IRQ 19 machine # [ 0.647069] ACPI: PCI: Interrupt link GSIE configured for IRQ 20 machine # [ 0.648066] ACPI: PCI: Interrupt link GSIF configured for IRQ 21 machine # [ 0.649067] ACPI: PCI: Interrupt link GSIG configured for IRQ 22 machine # [ 0.650076] ACPI: PCI: Interrupt link GSIH configured for IRQ 23 machine # [ 0.652195] iommu: Default domain type: Translated machine # [ 0.653058] iommu: DMA domain TLB invalidation policy: lazy mode machine # [ 0.654366] ACPI: bus type USB registered machine # [ 0.655136] usbcore: registered new interface driver usbfs machine # [ 0.656070] usbcore: registered new interface driver hub machine # [ 0.657077] usbcore: registered new device driver usb machine # [ 0.659279] NetLabel: Initializing machine # [ 0.660048] NetLabel: domain hash size = 128 machine # [ 0.661048] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO machine # [ 0.662116] NetLabel: unlabeled traffic allowed by default machine # [ 0.663066] PCI: Using ACPI for IRQ routing machine # [ 0.772153] pci 0000:00:01.0: vgaarb: setting as boot VGA device machine # [ 0.773043] pci 0000:00:01.0: vgaarb: bridge control possible machine # [ 0.773043] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none machine # [ 0.773052] vgaarb: loaded machine # [ 0.774284] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 machine # [ 0.775048] hpet0: 3 comparators, 64-bit 100.000000 MHz counter machine # [ 0.779148] clocksource: Switched to clocksource kvm-clock machine # [ 0.783463] VFS: Disk quotas dquot_6.6.0 machine # [ 0.784744] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.787016] pnp: PnP ACPI init machine # [ 0.788301] ACPI: IRQ 4 override to edge(!), high(!) machine # [ 0.789978] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved machine # [ 0.792698] pnp: PnP ACPI: found 6 devices machine # [ 0.800888] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns machine # [ 0.803603] clocksource: Switched to clocksource acpi_pm machine # [ 0.805332] NET: Registered PF_INET protocol family machine # [ 0.807095] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.825702] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.828257] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.830683] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.833100] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.835368] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.837409] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.839745] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.841780] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.844071] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.845879] NET: Registered PF_XDP protocol family machine # [ 0.847425] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] machine # [ 0.849322] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] machine # [ 0.851209] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] machine # [ 0.853329] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window] machine # [ 0.855402] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] machine # [ 0.857525] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] machine # [ 0.860311] ACPI: \_SB_.GSIA: Enabled at IRQ 16 machine # [ 0.863390] ACPI: \_SB_.GSIB: Enabled at IRQ 17 machine # [ 0.866200] ACPI: \_SB_.GSIC: Enabled at IRQ 18 machine # [ 0.869016] ACPI: \_SB_.GSID: Enabled at IRQ 19 machine # [ 0.871514] PCI: CLS 0 bytes, default 64 machine # [ 0.873141] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns machine # [ 0.876232] Trying to unpack rootfs image as initramfs... machine # [ 0.923894] Initialise system trusted keyrings machine # [ 0.928529] workingset: timestamp_bits=40 max_order=18 bucket_order=0 machine # [ 0.954056] Key type asymmetric registered machine # [ 0.957511] Asymmetric key parser 'x509' registered machine # [ 0.959110] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) machine # [ 0.966648] io scheduler mq-deadline registered machine # [ 0.970497] io scheduler kyber registered machine # [ 0.975078] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.977371] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A machine # [ 0.985674] Linux agpgart interface v0.103 machine # [ 0.987075] ACPI: bus type drm_connector registered machine # [ 0.992979] usbcore: registered new interface driver usbserial_generic machine # [ 0.994993] usbserial: USB Serial support registered for generic machine # [ 0.999491] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS. machine # [ 0.999491] Please enable it if your BIOS has the CPPC option. machine # [ 1.003677] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled machine # [ 1.012653] drop_monitor: Initializing network drop monitor service machine # [ 1.014717] NET: Registered PF_INET6 protocol family machine # [ 1.020937] Segment Routing with IPv6 machine # [ 1.025524] In-situ OAM (IOAM) with IPv6 machine # [ 1.029794] IPI shorthand broadcast: enabled machine # [ 1.036714] sched_clock: Marking stable (776030586, 260435845)->(1253995212, -217528781) machine # [ 1.044724] registered taskstats version 1 machine # [ 1.046291] Loading compiled-in X.509 certificates machine # [ 1.069486] Demotion targets for Node 0: null machine # [ 1.071079] Key type .fscrypt registered machine # [ 1.074473] Key type fscrypt-provisioning registered machine # [ 1.076137] ima: No TPM chip found, activating TPM-bypass! machine # [ 1.080484] ima: Allocated hash algorithm: sha1 machine # [ 1.081994] ima: No architecture policies found machine # [ 1.087678] PM: Magic number: 10:187:662 machine # [ 1.091926] RAS: Correctable Errors collector initialized. machine # [ 1.103585] clk: Disabling unused clocks machine # [ 1.106497] PM: genpd: Disabling unused power domains machine # [ 1.238044] Freeing initrd memory: 28332K machine # [ 1.241936] Freeing unused decrypted memory: 2028K machine # [ 1.245407] Freeing unused kernel image (initmem) memory: 3644K machine # [ 1.247390] Write protecting the kernel read-only data: 32768k machine # [ 1.250236] Freeing unused kernel image (text/rodata gap) memory: 1208K machine # [ 1.252812] Freeing unused kernel image (rodata/data gap) memory: 752K machine # [ 1.306797] x86/mm: Checked W+X mappings: passed, no W+X pages found. machine # [ 1.308785] Run /init as init process machine # [ 1.320339] systemd[1]: Inserted module 'autofs4' machine # [ 1.338094] fuse: init (API version 7.45) machine # [ 1.344851] ACPI: \_SB_.GSIG: Enabled at IRQ 22 machine # [ 1.348635] ACPI: \_SB_.GSIH: Enabled at IRQ 23 machine # [ 1.353411] ACPI: \_SB_.GSIE: Enabled at IRQ 20 machine # [ 1.357101] ACPI: \_SB_.GSIF: Enabled at IRQ 21 machine # [ 1.392841] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.732302] systemd[1]: systemd 262-aaab1f1 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) machine # [ 1.755763] systemd[1]: Detected virtualization kvm. machine # [ 1.760219] systemd[1]: Detected architecture x86-64. machine # [ 1.764827] systemd[1]: Running in initrd. machine # [ 1.769672] systemd[1]: Initializing machine ID from random generator. machine # [ 1.775640] systemd[1]: Hostname set to . machine # [ 1.860365] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.903769] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.909130] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.911276] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.913874] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.915923] systemd[1]: Reached target Path Units. machine # [ 1.917600] systemd[1]: Reached target Slice Units. machine # [ 1.919221] systemd[1]: Reached target Swaps. machine # [ 1.920748] systemd[1]: Reached target Timer Units. machine # [ 1.922601] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.924784] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.926888] systemd[1]: Listening on Journal Sockets. machine # [ 1.928699] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.930426] systemd[1]: Reached target Socket Units. machine # [ 1.933253] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.951756] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 1.965127] systemd[1]: Starting Load Kernel Module configfs... machine # [ 1.980287] systemd[1]: Starting Journal Service... machine # [ 1.995573] systemd[1]: Starting Load Kernel Modules... machine # [ 2.002626] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 2.007591] netfs: FS-Cache loaded machine # [ 2.015717] systemd[1]: Starting Coldplug All udev Devices... machine # [ 2.027123] 9pnet: Installing 9P2000 support machine # [ 2.043997] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 2.052237] systemd-journald[67]: Collecting audit messages is disabled. machine # [ 2.062555] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 2.072230] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 2.077615] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 2.084086] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 2.091549] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 2.096120] systemd[1]: Finished Load Kernel Module configfs. machine # [ 2.103361] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config machine # [ 2.123709] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 2.143672] systemd[1]: Finished Load Kernel Modules. machine # [ 2.156072] systemd[1]: Starting Apply Kernel Variables... machine # [ 2.175692] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 2.191029] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 2.211636] systemd[1]: Finished Apply Kernel Variables. machine # [ 2.230396] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 2.236881] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 2.245585] systemd[1]: Reached target Local File Systems. machine # [ 2.253757] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 2.005118] systemd-modules-load[68]: Inserted module 'dm_mod' machine # [ 2.007466] systemd-modules-load[68]: Inserted module 'virtio_balloon' machine # [ 2.011494] systemd-modules-load[68]: Inserted module 'virtio_gpu' machine # [ 2.275845] systemd[1]: Started Journal Service. machine # [ 2.039353] systemd[1]: Starting Create System Files and Directories... machine # [ 2.072671] systemd-udevd[75]: Using default interface naming scheme 'v261'. machine # [ 2.077700] systemd[1]: Finished Create System Files and Directories. machine # [ 2.112114] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 2.158761] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.160891] systemd[1]: Reached target System Initialization. machine # [ 2.164120] systemd[1]: Reached target Basic System. machine # [ 2.708958] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 machine # [ 2.730937] virtio_blk virtio5: 1/0/0 default/read/poll queues machine # [ 2.746510] serio: i8042 KBD port at 0x60,0x64 irq 1 machine # [ 2.747760] serio: i8042 AUX port at 0x60,0x64 irq 12 machine # [ 2.765694] ehci-pci 0000:00:1d.7: EHCI Host Controller machine # [ 2.767015] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1 machine # [ 2.769812] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 2.772385] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000 machine # [ 2.783048] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 machine # [ 2.784743] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18 machine # [ 2.790367] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.794480] usb usb1: Product: EHCI Host Controller machine # [ 2.796595] usb usb1: Manufacturer: Linux 6.18.50 ehci_hcd machine # [ 2.799480] usb usb1: SerialNumber: 0000:00:1d.7 machine # [ 2.803699] hub 1-0:1.0: USB hub found machine # [ 2.805159] hub 1-0:1.0: 6 ports detected machine # [ 2.811879] uhci_hcd 0000:00:1d.0: UHCI Host Controller machine # [ 2.813208] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2 machine # [ 2.816756] SCSI subsystem initialized machine # [ 2.832795] uhci_hcd 0000:00:1d.0: detected 2 ports machine # [ 2.844921] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c200 machine # [ 2.859382] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 2.876495] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.890773] usb usb2: Product: UHCI Host Controller machine # [ 2.892029] usb usb2: Manufacturer: Linux 6.18.50 uhci_hcd machine # [ 2.647322] systemd[1]: Starting Virtual Console Setup... machine # [ 2.656202] (udev-worker)[87]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.663430] (udev-worker)[94]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.668740] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.936654] usb usb2: SerialNumber: 0000:00:1d.0 machine # [ 2.695820] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.961092] hub 2-0:1.0: USB hub found machine # [ 2.967477] hub 2-0:1.0: 2 ports detected machine # [ 2.713626] systemd[1]: Finished Virtual Console Setup. machine # [ 2.983744] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 machine # [ 2.988253] uhci_hcd 0000:00:1d.1: UHCI Host Controller machine # [ 2.997536] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3 machine # [ 2.748061] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 2.749697] systemd[1]: Reached target Initrd Root Device. machine # [ 2.754109] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 3.016522] uhci_hcd 0000:00:1d.1: detected 2 ports machine # [ 3.022773] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c220 machine # [ 3.025635] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode machine # [ 3.032039] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 3.037273] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 3.040506] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) machine # [ 3.042616] usb usb3: Product: UHCI Host Controller machine # [ 3.043910] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 3.046270] ahci 0000:00:1f.2: flags: 64bit ncq only machine # [ 3.048728] usb usb3: Manufacturer: Linux 6.18.50 uhci_hcd machine # [ 3.056492] usb usb3: SerialNumber: 0000:00:1d.1 machine # [ 2.799727] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 3.063740] scsi host0: ahci machine # [ 2.807305] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 3.070566] hub 3-0:1.0: USB hub found machine # [ 2.812110] systemd[1]: Mounting /sysroot... machine # [ 3.077566] scsi host1: ahci machine # [ 3.079486] hub 3-0:1.0: 2 ports detected machine # [ 3.086383] scsi host2: ahci machine # [ 3.096086] scsi host3: ahci machine # [ 3.100607] uhci_hcd 0000:00:1d.2: UHCI Host Controller machine # [ 3.101885] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4 machine # [ 3.108379] scsi host4: ahci machine # [ 3.112740] scsi host5: ahci machine # [ 3.119152] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 44 lpm-pol 1 machine # [ 3.131698] uhci_hcd 0000:00:1d.2: detected 2 ports machine # [ 3.138699] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 44 lpm-pol 1 machine # [ 3.143627] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c240 machine # [ 3.148267] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 44 lpm-pol 1 machine # [ 3.153691] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 3.157636] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 44 lpm-pol 1 machine # [ 3.162544] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 3.166709] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 44 lpm-pol 1 machine # [ 3.171538] usb usb4: Product: UHCI Host Controller machine # [ 3.174550] usb usb4: Manufacturer: Linux 6.18.50 uhci_hcd machine # [ 3.178544] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 44 lpm-pol 1 machine # [ 3.182642] usb usb4: SerialNumber: 0000:00:1d.2 machine # [ 3.185470] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 machine # [ 3.187384] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 machine # [ 3.194566] usb 1-1: Product: QEMU USB Tablet machine # [ 3.196731] usb 1-1: Manufacturer: QEMU machine # [ 3.201479] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1 machine # [ 3.207585] hub 4-0:1.0: USB hub found machine # [ 3.213487] hub 4-0:1.0: 2 ports detected machine # [ 3.240308] hid: raw HID events driver (C) Jiri Kosina machine # [ 3.515548] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) machine # [ 3.517313] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 machine # [ 3.519044] ata3.00: applying bridge limits machine # [ 3.520531] ata5: SATA link down (SStatus 0 SControl 300) machine # [ 3.522204] ata6: SATA link down (SStatus 0 SControl 300) machine # [ 3.525725] ata2: SATA link down (SStatus 0 SControl 300) machine # [ 3.530095] ata4: SATA link down (SStatus 0 SControl 300) machine # [ 3.534162] ata1: SATA link down (SStatus 0 SControl 300) machine # [ 3.538630] ata3.00: configured for UDMA/100 machine # [ 3.542890] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 machine # [ 3.584579] EXT4-fs (vda): mounted filesystem dafbb668-457e-45fe-afd3-b0e08892c282 r/w with ordered data mode. Quota mode: none. machine # [ 3.333423] systemd[1]: Mounted /sysroot. machine # [ 3.336105] systemd[1]: Reached target Initrd Root File System. machine # [ 3.343073] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 3.362052] systemd-sysroot-fstab-check[140]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 3.369756] systemd[1]: Reload requested from client PID 140 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 3.372748] systemd[1]: Reloading... machine # [ 3.638066] usbcore: registered new interface driver usbhid machine # [ 3.658715] usbhid: USB HID core driver machine # [ 3.695761] 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/input2 machine # [ 3.710965] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0 machine # [ 3.717808] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray machine # [ 3.735487] cdrom: Uniform CD-ROM driver Revision: 3.20 machine # [ 3.505902] systemd[1]: Reloading finished in 137 ms. machine # [ 3.517559] systemd-sysroot-fstab-check[140]: Requesting initrd-fs.target/start/replace... machine # [ 3.524926] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio machine # [ 3.527655] systemd-sysroot-fstab-check[140]: Requesting swap.target/start/replace... machine # [ 3.530919] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 3.536177] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 3.537983] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 3.779657] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.789281] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 3.800484] systemd[1]: Mounting /sysroot/run... machine # [ 3.814932] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.826394] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.859869] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 4.130741] 9p: Installing v9fs 9p2000 file system support machine # [ 3.873394] systemd[1]: Mounted /sysroot/run. machine # [ 3.885561] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.887473] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.890132] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.894127] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.907068] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.909793] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.779267] systemd[1]: Mounting /sysroot/nix/store... machine # [ 4.825401] systemd[1]: Mounted /sysroot/nix/store. machine # [ 4.828998] systemd[1]: Reached target Initrd File Systems. machine # [ 4.835212] systemd[1]: Starting Find NixOS closure... machine # [ 4.840089] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 4.862651] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 4.867775] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. machine # [ 4.876548] systemd[1]: Finished Find NixOS closure. machine # [ 4.878724] systemd[1]: Reached target Initrd Default Target. machine # [ 4.881500] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 4.899334] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 4.901849] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 4.905636] systemd[1]: Stopped target Initrd Default Target. machine # [ 4.908283] systemd[1]: Stopped target Basic System. machine # [ 4.909715] systemd[1]: Stopped target Initrd Root Device. machine # [ 4.911288] systemd[1]: Stopped target Path Units. machine # [ 4.913238] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 4.915445] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 4.918254] systemd[1]: Stopped target Slice Units. machine # [ 4.919691] systemd[1]: Stopped target Socket Units. machine # [ 4.922205] systemd[1]: Stopped target System Initialization. machine # [ 4.923767] systemd[1]: Stopped target Swaps. machine # [ 4.925204] systemd[1]: Stopped target Timer Units. machine # [ 4.926597] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 4.929274] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 4.930880] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 4.932857] systemd[1]: Stopped Find NixOS closure. machine # [ 4.935743] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio machine # [ 4.938517] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.941580] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 4.944174] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.945650] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 4.947880] systemd[1]: Stopped Load Kernel Modules. machine # [ 4.950571] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 4.953080] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 4.956107] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 4.959472] systemd[1]: Stopped Create System Files and Directories. machine # [ 4.961267] systemd[1]: Stopped target Local File Systems. machine # [ 4.964976] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 4.966891] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 4.969387] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 4.972269] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 4.974277] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.978321] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.981777] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 4.985248] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 4.987846] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 4.990460] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.995116] systemd[1]: Starting Cleanup udev Database... machine # [ 4.996603] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 4.998592] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 5.000370] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 5.003237] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 5.005192] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 5.007249] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 5.024509] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 5.027390] systemd[1]: Finished Cleanup udev Database. machine # [ 5.029741] systemd[1]: Reached target Switch Root. machine # [ 5.032550] systemd[1]: Starting NixOS Activation... machine # [ 5.200888] initrd-nixos-activation-start[195]: booting system configuration /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test machine # [ 5.275209] initrd-nixos-activation-start[195]: running activation script... machine # [ 5.713767] initrd-nixos-activation-start[218]: setting up /etc... machine # [ 5.956286] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 5.960116] systemd[1]: Finished NixOS Activation. machine # [ 5.962335] systemd[1]: Starting Switch Root... machine # [ 5.976582] systemd[1]: Switching root. machine # [ 6.373132] systemd-journald[67]: Received SIGTERM from PID 1 (systemd). machine # [ 6.532302] NET: Registered PF_VSOCK protocol family machine # [ 6.923906] systemd[1]: systemd 262-aaab1f1 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) machine # [ 6.932821] systemd[1]: Detected virtualization kvm. machine # [ 6.936015] systemd[1]: Detected architecture x86-64. machine # [ 6.939348] systemd[1]: Detected first boot. machine # [ 6.947086] systemd[1]: Initializing machine ID from random generator. machine # [ 7.070215] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 7.201687] systemd[1]: Applying preset policy. machine # [ 7.698787] systemd[1]: Populated /etc with preset unit settings. machine # [ 8.223343] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 8.225889] systemd[1]: Stopped initrd-switch-root.service. machine # [ 8.229528] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 8.232982] systemd[1]: Created slice Slice /system/getty. machine # [ 8.235090] systemd[1]: Created slice User and Session Slice. machine # [ 8.236825] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 8.238890] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 8.240778] systemd[1]: Expecting device /dev/hvc0... machine # [ 8.254963] systemd[1]: Expecting device /dev/ttyS0... machine # [ 8.256314] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 8.257809] systemd[1]: Stopped target initrd-fs.target. machine # [ 8.259167] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 8.260731] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 8.262270] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 8.263937] systemd[1]: Reached target Path Units. machine # [ 8.265225] systemd[1]: Reached target Remote File Systems. machine # [ 8.266675] systemd[1]: Reached target Slice Units. machine # [ 8.267971] systemd[1]: Reached target Swaps. machine # [ 8.271802] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 8.276824] systemd[1]: Listening on Process Core Dump Socket. machine # [ 8.280643] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 8.284141] systemd[1]: Listening on Factory Reset Management. machine # [ 8.285806] systemd[1]: Listening on Hostname Service Socket. machine # [ 8.291813] systemd[1]: Starting Journal Log Access Socket... machine # [ 8.294227] systemd[1]: Listening on Journal Audit Socket. machine # [ 8.298183] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 8.300106] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 8.302362] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.304705] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 8.315793] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 8.317642] systemd[1]: Listening on udev Varlink Socket. machine # [ 8.322259] systemd[1]: Mounting Huge Pages File System... machine # [ 8.326621] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 8.334573] systemd[1]: Mounting Kernel Debug File System... machine # [ 8.341819] systemd[1]: Mounting Kernel Trace File System... machine # [ 8.351545] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 8.353795] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio machine # [ 8.367902] systemd[1]: Starting Load Kernel Module configfs... machine # [ 8.370743] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 8.373419] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 8.376651] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 8.390680] systemd[1]: Mounting FUSE Control File System... machine # [ 8.393174] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 8.423933] systemd[1]: Starting Journal Service... machine # [ 8.447416] systemd[1]: Starting Load Kernel Modules... machine # [ 8.462029] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 8.472582] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 8.486669] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.507778] systemd[1]: Starting Coldplug All udev Devices... machine # [ 8.511387] systemd-journald[289]: Collecting audit messages is enabled. machine # [ 8.547891] systemd[1]: Listening on Journal Log Access Socket. machine # [ 8.556069] systemd[1]: Mounted Huge Pages File System. machine # [ 8.561584] EXT4-fs (vda): re-mounted dafbb668-457e-45fe-afd3-b0e08892c282. machine # [ 8.563714] loop: module loaded machine # [ 8.566795] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 8.315875] systemd[1]: Queued start job for default target Multi-User System. machine # [ 8.578775] systemd[1]: Started Journal Service. machine # [ 8.321338] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 8.326058] systemd-modules-load[290]: Inserted module 'loop' machine # [ 8.331154] systemd[1]: Mounted Kernel Debug File System. machine # [ 8.334853] systemd[1]: Mounted Kernel Trace File System. machine # [ 8.337872] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 8.343336] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 8.346675] systemd[1]: Finished Load Kernel Module configfs. machine # [ 8.352184] systemd[1]: Mounted FUSE Control File System. machine # [ 8.358290] systemd[1]: Finished Load Kernel Modules. machine # [ 8.363143] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 8.383203] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 8.391113] systemd[1]: Mounting Kernel Configuration File System... machine # [ 8.398855] systemd-oomd[292]: No swap; memory pressure usage will be degraded machine # [ 8.402391] systemd[1]: Starting Firewall... machine # [ 8.417166] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 8.420058] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 8.434132] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 8.449099] systemd[1]: Starting Apply Kernel Variables... machine # [ 8.464249] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 8.468845] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.486191] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 8.762591] systemd-journald[289]: Received client request to flush runtime journal. machine # [ 8.774155] systemd[1]: Mounted Kernel Configuration File System. machine # [ 8.778222] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 8.780907] systemd[1]: Reached target First Boot Complete. machine # [ 8.786274] systemd[1]: Finished Apply Kernel Variables. machine # [ 8.788282] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 8.790555] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 8.793242] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 8.796224] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 8.798223] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 8.800944] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 8.897879] systemd-udevd[326]: Using default interface naming scheme 'v261'. machine # [ 8.903786] systemd[1]: Finished Coldplug All udev Devices. machine # [ 8.967145] systemd[1]: Mounting /run/wrappers... machine # [ 9.013976] systemd[1]: Mounted /run/wrappers. machine # [ 9.016253] systemd[1]: Reached target Local File Systems. machine # [ 9.020306] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 9.026103] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 9.028415] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 9.035343] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 9.044899] systemd[1]: Starting Create System Files and Directories... machine # [ 9.070127] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 9.111675] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 9.121562] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 9.189498] systemd[1]: Finished Create System Files and Directories. machine # [ 9.201943] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 9.208425] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 9.288380] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 9.340117] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 9.348928] systemd[1]: Starting Update is Completed... machine # [ 9.408500] systemd[1]: Finished Update is Completed. machine # [ 9.503605] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 9.603411] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 9.608108] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 9.612952] systemd[1]: Reached target System Initialization. machine # [ 9.615518] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 9.618543] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 9.621440] systemd[1]: Reached target Timer Units. machine # [ 9.624380] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 9.626613] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 9.630763] systemd[1]: Reached target Socket Units. machine # [ 9.633237] systemd[1]: Reached target Basic System. machine # [ 9.636755] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 9.646370] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 9.658263] systemd[1]: Starting Post-Boot Actions... machine # [ 9.672664] systemd[1]: Started Reset console on configuration changes. machine # [ 9.699107] systemd[1]: Starting resolvconf update... machine # [ 9.803370] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 9.813154] systemd[1]: Finished Post-Boot Actions. machine # [ 9.831243] nsncd[460]: Sep 11 02:41:59.296 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 9.838855] systemd[1]: Starting D-Bus System Message Bus... machine # [ 9.841060] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 9.846822] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 9.850623] systemd[1]: Reached target User and Group Name Lookups. machine # [ 9.877893] systemd[1]: Starting User Login Management... machine # [ 9.886226] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 9.894891] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped. machine # [ 9.929052] systemd[1]: Started backdoor.service. machine # [ 9.946293] (udev-worker)[409]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 9.967361] (udev-worker)[409]: Network interface NamePolicy= disabled on kernel command line. machine # [ 9.995752] (udev-worker)[417]: Network interface NamePolicy= disabled on kernel command line. machine # [ 10.040140] dbus-broker-launch[485]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 10.056723] dbus-broker-launch[485]: NSS returned no entry for 'systemd-timesync' machine # [ 10.060482] dbus-broker-launch[485]: Invalid user-name in /nix/store/3lj7qazpb810svcq8vbq2i9jqpcigr3b-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # connecting to host... machine # [ 10.103480] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 10.106742] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 10.110481] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 10.113944] systemd[1]: Stopping User and Group Name Lookups... machine # [ 10.118744] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 10.124399] systemd[1]: Started D-Bus System Message Bus. machine # [ 10.131671] systemd[1]: nscd.service: Deactivated successfully. machine # [ 10.141437] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine: Guest shell says: b'Spawning backdoor root shell...\n' machine: connected to guest root shell machine: (connecting took 11.13 seconds) machine: (finished: waiting for the VM to finish booting, in 11.35 seconds) machine # [ 10.167560] systemd-logind[497]: New seat seat0. machine # [ 10.170391] systemd[1]: Started User Login Management. machine # [ 10.188408] dbus-broker-launch[485]: Ready machine # [ 10.192350] systemd[1]: Starting linger-users.service... machine # [ 10.209700] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 10.224145] systemd[1]: Finished Firewall. machine # [ 10.303890] nsncd[581]: Sep 11 02:41:59.770 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 10.362295] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 10.367576] systemd[1]: Finished resolvconf update. machine # [ 10.375602] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 10.393416] systemd[1]: Reached target Preparation for Network. machine # [ 10.397487] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 10.399180] systemd[1]: Reached target User and Group Name Lookups. machine # [ 10.408309] systemd[1]: Starting DHCP Client... machine # [ 10.416104] systemd[1]: Starting Address configuration of eth1... machine # [ 10.425856] systemd[1]: Starting Extra networking commands.... machine # [ 10.518700] systemd[1]: Created slice Slice /user/1001. machine # [ 10.520278] systemd[1]: Starting User Runtime Directory /run/user/1001... machine # [ 10.536672] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 10.541734] systemd[1]: Finished linger-users.service. machine # [ 10.864672] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console machine # [ 10.871268] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 machine # [ 10.890563] mousedev: PS/2 mouse device common for all mice machine # [ 10.680604] systemd[1]: Finished User Runtime Directory /run/user/1001. machine # [ 10.692131] systemd[1]: Starting User Manager for UID 1001... machine # [ 10.907521] ACPI: button: Power Button [PWRF] machine # [ 11.003175] rtc_cmos 00:04: RTC can wake from S4 machine # [ 11.025681] rtc_cmos 00:04: registered as rtc0 machine # [ 11.025807] rtc_cmos 00:04: setting system clock to 2026-09-11T02:41:59 UTC (1789094519) machine # [ 11.025919] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs machine # [ 11.026957] systemd-journald[289]: Time jumped backwards, rotating. machine # [ 11.040160] parport_pc 00:02: reported by Plug and Play ACPI machine # [ 10.946718] (systemd)[620]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 11.040280] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] machine # [ 11.059743] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:09.0/virtio7/input/input4 machine # [ 10.986324] systemd-logind[497]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'. machine # [ 11.002894] network-addresses-eth1-start[607]: adding address 192.168.1.1/24... done machine # [ 11.005537] network-addresses-eth1-start[607]: adding address 2001:db8:1::1/64... done machine # [ 11.068789] Console: switching to colour dummy device 80x25 machine # [ 11.138837] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized machine # [ 11.036730] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 11.279271] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt machine # [ 11.279470] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD machine # [ 11.361397] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6 machine # [ 11.361829] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5 machine # [ 11.410904] [drm] Found bochs VGA, ID 0xb0c5. machine # [ 11.410907] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. machine # [ 11.413958] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic machine # [ 11.415321] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0 machine # [ 11.416217] systemd[1]: Finished Address configuration of eth1. machine # [ 11.430912] systemd[620]: Queued start job for default target Main User Target. machine # [ 11.683349] Console: switching to colour frame buffer device 160x50 machine # [ 11.448576] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 11.728693] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device machine # [ 11.478676] systemd[620]: Created slice User Application Slice. machine # [ 11.487900] systemd[620]: Started Daily Cleanup of User's Temporary Directories. machine # [ 11.753702] ppdev: user-space parallel port driver machine # [ 11.501829] systemd[620]: Reached target Paths. machine # [ 11.519118] systemd[620]: Reached target Timers. machine # [ 11.528819] systemd[620]: Starting D-Bus User Message Bus Socket... machine # [ 11.804575] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660) machine # [ 11.546762] systemd[620]: Starting Create User Files and Directories... machine # [ 11.560518] systemd[620]: Finished Create User Files and Directories. machine # [ 11.564672] systemd[1]: Starting Virtual Console Setup... machine # [ 11.576770] systemd[620]: Listening on D-Bus User Message Bus Socket. machine # [ 11.840470] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0) machine # [ 11.592713] systemd[620]: Reached target Sockets. machine # [ 11.602190] systemd[620]: Reached target Basic System. machine # [ 11.606370] systemd[1]: Started User Manager for UID 1001. machine # [ 11.614515] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 11.637354] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 11.645782] dhcpcd[626]: dhcpcd-10.3.2 starting machine # [ 11.657803] systemd[620]: Reached target Main User Target. machine # [ 11.661624] systemd[620]: Startup finished in 473ms. machine # [ 11.668796] systemd-logind[497]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) machine # [ 11.675609] systemd-logind[497]: Watching system buttons on /dev/input/event2 (Power Button) machine # [ 11.679172] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 11.684578] systemd[1]: Stopped Virtual Console Setup. machine # [ 11.687396] systemd[1]: Starting Virtual Console Setup... machine # [ 11.693338] systemd-logind[497]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 11.702340] dhcpcd[706]: dev: loaded udev machine # [ 11.705342] systemd[1]: Finished Extra networking commands.. machine # [ 11.709772] systemd[1]: Reached target Network. machine # [ 11.713537] systemd[1]: Starting Permit User Sessions... machine # [ 11.717444] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 11.721228] systemd[1]: Stopped Virtual Console Setup. machine # [ 11.723200] systemd[1]: Starting Virtual Console Setup... machine # [ 11.995970] 8021q: 802.1Q VLAN Support v1.8 machine # [ 11.999476] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 11.782253] systemd[1]: Finished Permit User Sessions. machine # [ 11.789199] systemd[1]: Started Getty on tty1. machine # [ 11.791433] systemd[1]: Reached target Login Prompts. machine # [ 11.819596] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 12.154240] kvm_amd: TSC scaling supported machine # [ 12.155169] kvm_amd: Nested Virtualization enabled machine # [ 12.156723] kvm_amd: Nested Paging enabled machine # [ 12.157663] kvm_amd: LBR virtualization supported machine # [ 12.159899] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 12.162567] kvm_amd: Virtual VMLOAD VMSAVE supported machine # [ 12.163973] kvm_amd: Virtual GIF supported machine # [ 12.165239] kvm_amd: Virtual NMI enabled machine # [ 12.203558] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 12.205789] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 12.211759] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 12.213448] cfg80211: failed to load regulatory.db machine # [ 12.229089] EDAC MC: Ver: 3.0.0 machine # [ 12.314947] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 12.056309] dhcpcd[706]: eth0: waiting for carrier machine # [ 12.057825] dhcpcd[706]: eth0: carrier acquired machine # [ 12.070363] dhcpcd[706]: DUID 00:01:00:01:32:36:26:f8:52:54:00:12:34:56 machine # [ 12.072503] dhcpcd[706]: eth0: IAID 00:12:34:56 machine # [ 12.073991] dhcpcd[706]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 12.404461] systemd-vconsole-setup[708]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 12.409227] systemd[1]: Finished Virtual Console Setup. machine # [ 12.714772] dhcpcd[706]: eth0: soliciting a DHCP lease machine # [ 12.989629] NET: Registered PF_PACKET protocol family machine # [ 12.733599] dhcpcd[706]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 12.737231] dhcpcd[706]: eth0: probing address 10.0.2.15/24 machine # [ 13.573838] dhcpcd[706]: eth0: soliciting an IPv6 router machine # [ 13.577943] dhcpcd[706]: eth0: Router Advertisement from fe80::2 machine # [ 13.583316] dhcpcd[706]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 13.587757] dhcpcd[706]: eth0: adding route to fec0::/64 machine # [ 13.591899] dhcpcd[706]: eth0: adding default route via fe80::2 machine # [ 17.760899] dhcpcd[706]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 17.766560] dhcpcd[706]: eth0: adding route to 10.0.2.0/24 machine # [ 17.770421] dhcpcd[706]: eth0: adding default route via 10.0.2.2 machine # [ 17.901710] systemd[1]: Started DHCP Client. machine # [ 17.904781] systemd[1]: Reached target Multi-User System. machine # [ 17.906383] systemd[1]: Startup finished in 1.052s (kernel) + 5.148s (initrd) + 11.703s (userspace) = 17.904s. machine: (finished: waiting for unit multi-user.target, in 19.60 seconds) machine: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/bin/switch-to-configuration test machine # Checking switch inhibitors... done machine # [ 19.044443] nixos[856]: switching to system configuration /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test machine # [ 19.048404] systemd[1]: Stopped target Remote File Systems. machine # [ 19.051339] systemd[1]: Stopped target Local File Systems. machine # activating the configuration... machine # setting up /etc... machine # [ 19.486939] systemd[1]: Reload requested from client PID 856 ('.switch-to-conf') (unit backdoor.service)... machine # [ 19.489992] systemd[1]: Reloading... machine # [ 20.045104] systemd[1]: Reloading finished in 552 ms. machine # reloading user units for usertest... machine # [ 20.086873] systemd[620]: Created slice User Core Session Slice. machine # [ 20.091397] systemd[620]: Starting D-Bus User Message Bus... machine # [ 20.154604] dbus-broker-launch[927]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/3lj7qazpb810svcq8vbq2i9jqpcigr3b-system-path/share/dbus-1/services/org.freedesktop.import1.service' machine # [ 20.159474] dbus-broker-launch[927]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/3lj7qazpb810svcq8vbq2i9jqpcigr3b-system-path/share/dbus-1/services/org.freedesktop.machine1.service' machine # [ 20.164414] dbus-broker-launch[927]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/3lj7qazpb810svcq8vbq2i9jqpcigr3b-system-path/share/dbus-1/services/org.freedesktop.portable1.service' machine # [ 20.169263] dbus-broker-launch[927]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/3lj7qazpb810svcq8vbq2i9jqpcigr3b-system-path/share/dbus-1/services/org.freedesktop.systemd1.service' machine # [ 20.174669] dbus-broker-launch[927]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored machine # [ 20.177508] dbus-broker-launch[927]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored machine # [ 20.180963] systemd[620]: Started D-Bus User Message Bus. machine # [ 20.190447] dbus-broker-launch[927]: Ready machine # [ 20.311476] systemd[620]: Reexecution requested from client PID 926 ('switch-to-confi')... machine # [ 20.315100] systemd[620]: Reexecuting. machine # [ 20.347761] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 20.519125] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 20.528526] systemd[620]: Reload requested from client PID 926 ('switch-to-confi')... machine # [ 20.530807] systemd[620]: Reloading... machine # [ 20.699707] systemd[620]: Reloading finished in 166 ms. machine # [ 20.705992] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 20.718254] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 20.721226] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 20.725079] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 20.766206] systemd[620]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 20.783909] systemd[1]: Starting Re-setup tmpfiles on a system that is already running.... machine # [ 20.920987] systemd[1]: Finished Re-setup tmpfiles on a system that is already running.. machine # [ 20.924925] systemd[1]: Reached target Reactivate sysinit units. machine # [ 20.929533] systemd[1]: Reached target Remote File Systems. machine # [ 20.934662] systemd[1]: Starting linger-users.service... machine # [ 20.940292] systemd[1]: Reached target Local File Systems. machine # [ 20.946976] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 20.954566] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 20.957423] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 20.959708] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 20.966217] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 20.971326] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 20.977235] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 20.982916] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 20.985616] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 21.045262] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 21.048261] systemd[1]: Finished linger-users.service. machine # [ 21.335097] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 21.338802] systemd[1]: Finished Create SUID/SGID Wrappers. machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service machine # [ 21.599869] nixos[856]: finished switching to system configuration /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/bin/switch-to-configuration test, in 3.20 seconds) machine: must succeed: rm /etc/NIXOS machine: (finished: must succeed: rm /etc/NIXOS, in 0.03 seconds) machine: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/krl7lvy3bdkzvmmrmxmrh4n93xqw771k-nixos-system-other-test/bin/switch-to-configuration test machine # this should succeed (config: /nix/store/krl7lvy3bdkzvmmrmxmrh4n93xqw771k-nixos-system-other-test, action: test) machine # Checking switch inhibitors... done machine # [ 22.250425] nixos[1005]: switching to system configuration /nix/store/krl7lvy3bdkzvmmrmxmrh4n93xqw771k-nixos-system-other-test machine # [ 22.254931] systemd[1]: Stopped target Remote File Systems. machine # activating the configuration... machine # [ 22.258254] systemd[1]: Stopped target Local File Systems. machine # removing user ‘usertest’ machine # setting up /etc... machine # [ 22.757304] systemd[1]: Reload requested from client PID 1005 ('.switch-to-conf') (unit backdoor.service)... machine # [ 22.760207] systemd[1]: Reloading... machine # [ 23.396976] systemd[1]: Reloading finished in 633 ms. machine # reloading user units for usertest... machine # [ 23.555974] systemd[620]: Reexecution requested from client PID 1076 ('switch-to-confi')... machine # [ 23.560165] systemd[620]: Reexecuting. machine # [ 23.589422] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.772561] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.781087] systemd[620]: Reload requested from client PID 1076 ('switch-to-confi')... machine # [ 23.784486] systemd[620]: Reloading... machine # [ 23.950218] systemd[620]: Reloading finished in 163 ms. machine # [ 23.956989] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 23.969293] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 23.972104] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 23.975993] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 24.017450] systemd[620]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 24.024088] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 24.025923] systemd[1]: Stopping Reactivate sysinit units... machine # [ 24.027725] systemd[1]: Reached target Reactivate sysinit units. machine # reloading the following units: dbus-broker.service machine # [ 24.032786] dbus-broker-launch[485]: Caught SIGHUP, trigger reload. machine # [ 24.035491] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 24.057942] dbus-broker-launch[485]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 24.061333] dbus-broker-launch[485]: NSS returned no entry for 'systemd-timesync' machine # [ 24.063397] dbus-broker-launch[485]: Invalid user-name in /nix/store/pfqi8v8aaz0zqjc3s0jh36ap84csxy61-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 24.089645] systemd[1]: Reloaded D-Bus System Message Bus. machine # restarting the following units: network-addresses-eth1.service machine # [ 24.098346] systemd[1]: Stopping Address configuration of eth1... machine # [ 24.145911] network-addresses-eth1-pre-stop[1088]: deleting address 192.168.1.1/24... done machine # [ 24.159381] network-addresses-eth1-pre-stop[1088]: deleting address 2001:db8:1::1/64... done machine # [ 24.169861] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 24.172762] systemd[1]: Stopped Address configuration of eth1. machine # [ 24.178690] systemd[1]: Starting Address configuration of eth1... machine # [ 24.252379] network-addresses-eth1-start[1093]: adding address 192.168.1.2/24... done machine # [ 24.265397] network-addresses-eth1-start[1093]: adding address 2001:db8:1::2/64... done machine # [ 24.281613] systemd[1]: Finished Address configuration of eth1. machine # [ 24.287684] systemd[1]: Reached target Remote File Systems. machine # [ 24.292685] systemd[1]: Starting linger-users.service... machine # [ 24.304950] systemd[1]: Reached target Local File Systems. machine # [ 24.312212] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 24.319470] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 24.323354] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 24.325775] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 24.330777] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 24.335344] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.339198] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.342226] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.344822] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 24.380578] linger-users-start[1107]: id: ‘usertest’: no such user machine # [ 24.383516] linger-users-start[1104]: Removing linger for missing user usertest machine # [ 24.394424] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 24.397332] systemd[1]: Finished linger-users.service. machine # [ 24.747738] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 24.750613] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 25.008337] nixos[1005]: finished switching to system configuration /nix/store/krl7lvy3bdkzvmmrmxmrh4n93xqw771k-nixos-system-other-test machine: (finished: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/krl7lvy3bdkzvmmrmxmrh4n93xqw771k-nixos-system-other-test/bin/switch-to-configuration test, in 3.38 seconds) subtest: services machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 25.532180] nixos[1172]: switching to system configuration /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test machine # [ 25.537388] systemd[1]: Stopped target Local File Systems. machine # [ 25.539606] systemd[1]: Stopped target Remote File Systems. machine # [ 25.955698] systemd[1]: Reload requested from client PID 1172 ('.switch-to-conf') (unit backdoor.service)... machine # [ 25.958624] systemd[1]: Reloading... machine # [ 26.474979] systemd[1]: Reloading finished in 513 ms. machine # [ 26.624279] systemd[620]: Reexecution requested from client PID 1241 ('switch-to-confi')... machine # [ 26.627529] systemd[620]: Reexecuting. machine # [ 26.657522] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 26.824898] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 26.833339] systemd[620]: Reload requested from client PID 1241 ('switch-to-confi')... machine # [ 26.835686] systemd[620]: Reloading... machine # [ 26.994446] systemd[620]: Reloading finished in 156 ms. machine # [ 26.999985] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.011112] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 27.012796] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 27.016852] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 27.051700] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 27.057262] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 27.059562] systemd[1]: Stopping Reactivate sysinit units... machine # [ 27.062239] systemd[1]: Reached target Reactivate sysinit units. machine # [ 27.065340] dbus-broker-launch[485]: Caught SIGHUP, trigger reload. machine # [ 27.067301] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 27.079778] dbus-broker-launch[485]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 27.084830] dbus-broker-launch[485]: NSS returned no entry for 'systemd-timesync' machine # [ 27.086667] dbus-broker-launch[485]: Invalid user-name in /nix/store/3lj7qazpb810svcq8vbq2i9jqpcigr3b-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 27.103652] systemd[1]: Reloaded D-Bus System Message Bus. machine # [ 27.110365] systemd[1]: Stopping Address configuration of eth1... machine # [ 27.147403] network-addresses-eth1-pre-stop[1253]: deleting address 192.168.1.2/24... done machine # [ 27.157751] network-addresses-eth1-pre-stop[1253]: deleting address 2001:db8:1::2/64... done machine # [ 27.166078] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 27.168901] systemd[1]: Stopped Address configuration of eth1. machine # [ 27.174487] systemd[1]: Starting Address configuration of eth1... machine # [ 27.239727] network-addresses-eth1-start[1258]: adding address 192.168.1.1/24... done machine # [ 27.253974] network-addresses-eth1-start[1258]: adding address 2001:db8:1::1/64... done machine # [ 27.270179] systemd[1]: Finished Address configuration of eth1. machine # [ 27.277094] systemd[1]: Reached target Remote File Systems. machine # [ 27.281673] systemd[1]: Starting linger-users.service... machine # [ 27.285585] systemd[1]: Reached target Local File Systems. machine # [ 27.288830] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 27.292683] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 27.296896] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 27.299809] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 27.306290] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 27.309209] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.312598] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.323096] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.325374] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 27.375550] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 27.380176] systemd[1]: Finished linger-users.service. machine # [ 27.668493] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 27.672252] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 27.939206] nixos[1172]: finished switching to system configuration /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.93 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 28.546162] nixos[1327]: switching to system configuration /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test machine # [ 28.550084] systemd[1]: Stopped target Remote File Systems. machine # [ 28.552937] systemd[1]: Stopped target Local File Systems. machine # [ 28.992808] systemd[1]: Reload requested from client PID 1327 ('.switch-to-conf') (unit backdoor.service)... machine # [ 28.995683] systemd[1]: Reloading... machine # [ 29.554330] systemd[1]: Reloading finished in 555 ms. machine # [ 29.699357] systemd[620]: Reexecution requested from client PID 1397 ('switch-to-confi')... machine # [ 29.701717] systemd[620]: Reexecuting. machine # [ 29.728434] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.888870] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.898973] systemd[620]: Reload requested from client PID 1397 ('switch-to-confi')... machine # [ 29.901264] systemd[620]: Reloading... machine # [ 30.069644] systemd[620]: Reloading finished in 165 ms. machine # [ 30.074988] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 30.085577] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 30.087392] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 30.092686] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 30.134119] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 30.138651] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 30.140929] systemd[1]: Stopping Reactivate sysinit units... machine # [ 30.143400] systemd[1]: Reached target Reactivate sysinit units. machine # [ 30.148845] systemd[1]: Reached target Local File Systems. machine # [ 30.152558] systemd[1]: Reached target Remote File Systems. machine # [ 30.159261] systemd[1]: Starting linger-users.service... machine # [ 30.169814] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 30.178230] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 30.182837] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 30.186378] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 30.190659] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 30.195195] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 30.198208] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 30.200955] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 30.204214] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 30.259089] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 30.261949] systemd[1]: Finished linger-users.service. machine # [ 30.523863] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 30.526712] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 30.787431] nixos[1327]: finished switching to system configuration /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.85 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1 machine # [ 31.497615] nixos[1468]: switching to system configuration /nix/store/m48h2c5bsb4w7lh6l2bms39nlnbzhvyr-nixos-system-machine-test machine # [ 31.501605] systemd[1]: Stopped target Local File Systems. machine # [ 31.505126] systemd[1]: Stopped target Remote File Systems. machine # [ 32.007518] systemd[1]: Reload requested from client PID 1468 ('.switch-to-conf') (unit backdoor.service)... machine # [ 32.010473] systemd[1]: Reloading... machine # [ 32.641350] systemd[1]: Reloading finished in 627 ms. machine # [ 32.794329] systemd[620]: Reexecution requested from client PID 1538 ('switch-to-confi')... machine # [ 32.798507] systemd[620]: Reexecuting. machine # [ 32.828657] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.000608] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.009046] systemd[620]: Reload requested from client PID 1538 ('switch-to-confi')... machine # [ 33.012180] systemd[620]: Reloading... machine # [ 33.184742] systemd[620]: Reloading finished in 170 ms. machine # [ 33.190042] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.201526] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 33.203273] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 33.207607] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 33.249411] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 33.254169] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 33.256601] systemd[1]: Stopping Reactivate sysinit units... machine # [ 33.258393] systemd[1]: Reached target Reactivate sysinit units. machine # [ 33.269720] systemd[1]: Reached target Remote File Systems. machine # [ 33.275824] systemd[1]: Starting linger-users.service... machine # [ 33.280201] systemd[1]: Starting test.service... machine # [ 33.284936] systemd[1]: Reached target Local File Systems. machine # [ 33.288106] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 33.296683] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 33.298804] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 33.303255] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 33.307941] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 33.312217] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.314458] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.321301] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.325353] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 33.359405] systemd[1]: Finished test.service. machine # [ 33.393385] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 33.396340] systemd[1]: Finished linger-users.service. machine # [ 33.680343] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 33.684588] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 33.940275] nixos[1468]: finished switching to system configuration /nix/store/m48h2c5bsb4w7lh6l2bms39nlnbzhvyr-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1, in 3.15 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1 machine # [ 34.428758] nixos[1611]: switching to system configuration /nix/store/m48h2c5bsb4w7lh6l2bms39nlnbzhvyr-nixos-system-machine-test machine # [ 34.433765] systemd[1]: Stopped target Remote File Systems. machine # [ 34.435461] systemd[1]: Stopped target Local File Systems. machine # [ 34.849144] systemd[1]: Reload requested from client PID 1611 ('.switch-to-conf') (unit backdoor.service)... machine # [ 34.852105] systemd[1]: Reloading... machine # [ 35.431993] systemd[1]: Reloading finished in 577 ms. machine # [ 35.578057] systemd[620]: Reexecution requested from client PID 1681 ('switch-to-confi')... machine # [ 35.580366] systemd[620]: Reexecuting. machine # [ 35.611477] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.774939] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.783720] systemd[620]: Reload requested from client PID 1681 ('switch-to-confi')... machine # [ 35.786578] systemd[620]: Reloading... machine # [ 35.952750] systemd[620]: Reloading finished in 163 ms. machine # [ 35.957989] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.969477] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 35.971197] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 35.975608] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 36.015880] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 36.020768] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 36.023377] systemd[1]: Stopping Reactivate sysinit units... machine # [ 36.025219] systemd[1]: Reached target Reactivate sysinit units. machine # [ 36.028202] systemd[1]: Reached target Local File Systems. machine # [ 36.034147] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 36.041493] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 36.044513] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 36.046762] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 36.051466] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 36.057212] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.059531] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.062161] systemd[1]: Reached target Remote File Systems. machine # [ 36.070067] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.072405] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 36.392861] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 36.395112] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 36.401516] systemd[1]: Starting linger-users.service... machine # [ 36.452670] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 36.455337] systemd[1]: Finished linger-users.service. machine # [ 36.715744] nixos[1611]: finished switching to system configuration /nix/store/m48h2c5bsb4w7lh6l2bms39nlnbzhvyr-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1, in 2.78 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceDifferentDescription/bin/switch-to-configuration test 2>&1 machine # [ 37.341729] nixos[1752]: switching to system configuration /nix/store/zrvclwyr7fv7d64139n05bz7rn81ww2h-nixos-system-machine-test machine # [ 37.345684] systemd[1]: Stopped target Local File Systems. machine # [ 37.348585] systemd[1]: Stopped target Remote File Systems. machine # [ 37.890583] systemd[1]: Reload requested from client PID 1752 ('.switch-to-conf') (unit backdoor.service)... machine # [ 37.893608] systemd[1]: Reloading... machine # [ 38.539418] systemd[1]: Reloading finished in 642 ms. machine # [ 38.684776] systemd[620]: Reexecution requested from client PID 1822 ('switch-to-confi')... machine # [ 38.689316] systemd[620]: Reexecuting. machine # [ 38.714558] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 38.875202] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 38.883129] systemd[620]: Reload requested from client PID 1822 ('switch-to-confi')... machine # [ 38.885412] systemd[620]: Reloading... machine # [ 39.032860] systemd[620]: Reloading finished in 143 ms. machine # [ 39.038052] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.049480] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 39.051174] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 39.055469] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 39.095576] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 39.100444] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 39.102802] systemd[1]: Stopping Reactivate sysinit units... machine # [ 39.104515] systemd[1]: Reached target Reactivate sysinit units. machine # [ 39.107528] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.110896] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 39.121814] systemd[1]: Reached target Local File Systems. machine # [ 39.127301] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 39.133238] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 39.135546] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 39.139835] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 39.147110] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 39.149834] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.152584] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.155833] systemd[1]: Reached target Remote File Systems. machine # [ 39.478651] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 39.482319] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 39.488367] systemd[1]: Starting linger-users.service... machine # [ 39.544564] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 39.547308] systemd[1]: Finished linger-users.service. machine # [ 39.803418] nixos[1752]: finished switching to system configuration /nix/store/zrvclwyr7fv7d64139n05bz7rn81ww2h-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceDifferentDescription/bin/switch-to-configuration test 2>&1, in 3.08 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1 machine # [ 40.366705] nixos[1893]: switching to system configuration /nix/store/hhmdwyxhlnnwkb7jlg96vns3jcqzpjj7-nixos-system-machine-test machine # [ 40.370668] systemd[1]: Stopped target Remote File Systems. machine # [ 40.373505] systemd[1]: test.service: Deactivated successfully. machine # [ 40.376189] systemd[1]: Stopped Test unit. machine # [ 40.378736] systemd[1]: Stopped target Local File Systems. machine # [ 40.913598] systemd[1]: Reload requested from client PID 1893 ('.switch-to-conf') (unit backdoor.service)... machine # [ 40.916544] systemd[1]: Reloading... machine # [ 41.524127] systemd[1]: Reloading finished in 604 ms. machine # [ 41.662802] systemd[620]: Reexecution requested from client PID 1963 ('switch-to-confi')... machine # [ 41.667142] systemd[620]: Reexecuting. machine # [ 41.692972] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 41.845750] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 41.854234] systemd[620]: Reload requested from client PID 1963 ('switch-to-confi')... machine # [ 41.856976] systemd[620]: Reloading... machine # [ 42.019175] systemd[620]: Reloading finished in 160 ms. machine # [ 42.025993] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.036774] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 42.038573] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 42.043787] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 42.084447] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 42.089689] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 42.092424] systemd[1]: Stopping Reactivate sysinit units... machine # [ 42.094209] systemd[1]: Reached target Reactivate sysinit units. machine # [ 42.099896] systemd[1]: Reached target Remote File Systems. machine # [ 42.103191] systemd[1]: Starting linger-users.service... machine # [ 42.108522] systemd[1]: Starting test.service... machine # [ 42.124258] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 42.126604] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 42.136408] systemd[1]: Reached target Local File Systems. machine # [ 42.140295] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 42.145469] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 42.148467] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 42.150747] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 42.154520] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 42.160329] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 42.162634] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 42.190682] systemd[1]: Finished test.service. machine # [ 42.222249] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 42.225402] systemd[1]: Finished linger-users.service. machine # [ 42.515465] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 42.518351] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 42.779571] nixos[1893]: finished switching to system configuration /nix/store/hhmdwyxhlnnwkb7jlg96vns3jcqzpjj7-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1, in 2.98 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 43.455820] nixos[2036]: switching to system configuration /nix/store/5sdxz4fkwdwydf5jc38a3dsc5hiir1bj-nixos-system-machine-test machine # [ 43.459760] systemd[1]: Stopped target Local File Systems. machine # [ 43.463260] systemd[1]: Stopped target Remote File Systems. machine # [ 43.957131] systemd[1]: Reload requested from client PID 2036 ('.switch-to-conf') (unit backdoor.service)... machine # [ 43.960489] systemd[1]: Reloading... machine # [ 44.579056] systemd[1]: Reloading finished in 615 ms. machine # [ 44.750820] systemd[620]: Reexecution requested from client PID 2105 ('switch-to-confi')... machine # [ 44.754930] systemd[620]: Reexecuting. machine # [ 44.785646] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 44.959955] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 44.968693] systemd[620]: Reload requested from client PID 2105 ('switch-to-confi')... machine # [ 44.971187] systemd[620]: Reloading... machine # [ 45.145856] systemd[620]: Reloading finished in 172 ms. machine # [ 45.151051] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.161578] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 45.164374] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 45.168566] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 45.212226] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 45.216804] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 45.219558] systemd[1]: Stopping Reactivate sysinit units... machine # [ 45.221683] systemd[1]: Reached target Reactivate sysinit units. machine # [ 45.224518] systemd[1]: test.service: Deactivated successfully. machine # [ 45.226223] systemd[1]: Stopped test.service. machine # [ 45.228367] systemd[1]: Stopping test.service... machine # [ 45.231840] systemd[1]: Starting test.service... machine # [ 45.263899] systemd[1]: Finished test.service. machine # [ 45.267441] systemd[1]: Reached target Remote File Systems. machine # [ 45.270989] systemd[1]: Reached target Local File Systems. machine # [ 45.274314] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 45.280281] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 45.283285] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 45.285517] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 45.292165] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 45.296705] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 45.298963] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 45.303584] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 45.305885] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 45.647349] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 45.650211] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 45.656610] systemd[1]: Starting linger-users.service... machine # [ 45.712984] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 45.716695] systemd[1]: Finished linger-users.service. machine # [ 45.976535] nixos[2036]: finished switching to system configuration /nix/store/5sdxz4fkwdwydf5jc38a3dsc5hiir1bj-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 3.20 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceReload/bin/switch-to-configuration test 2>&1 machine # [ 46.677754] nixos[2179]: switching to system configuration /nix/store/r3bmfjq4g0f22nz254xwb85mn0zlw197-nixos-system-machine-test machine # [ 46.681983] systemd[1]: Stopped target Local File Systems. machine # [ 46.684866] systemd[1]: Stopped target Remote File Systems. machine # [ 47.184262] systemd[1]: Reload requested from client PID 2179 ('.switch-to-conf') (unit backdoor.service)... machine # [ 47.187330] systemd[1]: Reloading... machine # [ 47.838284] systemd[1]: Reloading finished in 647 ms. machine # [ 47.967145] systemd[620]: Reexecution requested from client PID 2248 ('switch-to-confi')... machine # [ 47.971323] systemd[620]: Reexecuting. machine # [ 47.996601] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.162295] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.171223] systemd[620]: Reload requested from client PID 2248 ('switch-to-confi')... machine # [ 48.174161] systemd[620]: Reloading... machine # [ 48.310718] systemd[620]: Reloading finished in 134 ms. machine # [ 48.317059] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.328399] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 48.330218] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 48.334670] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 48.368727] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 48.373170] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 48.375411] systemd[1]: Stopping Reactivate sysinit units... machine # [ 48.377936] systemd[1]: Reached target Reactivate sysinit units. machine # [ 48.382610] systemd[1]: Reloading test.service... machine # [ 48.408106] systemd[1]: Reloaded test.service. machine # [ 48.412664] systemd[1]: Reached target Remote File Systems. machine # [ 48.421102] systemd[1]: Reached target Local File Systems. machine # [ 48.422615] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 48.427328] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 48.430273] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 48.432533] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 48.440328] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 48.443367] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.445712] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.449756] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.452106] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 48.810232] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 48.813109] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 48.819584] systemd[1]: Starting linger-users.service... machine # [ 48.877161] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 48.881213] systemd[1]: Finished linger-users.service. machine # [ 49.140296] nixos[2179]: finished switching to system configuration /nix/store/r3bmfjq4g0f22nz254xwb85mn0zlw197-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceReload/bin/switch-to-configuration test 2>&1, in 3.16 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1 machine # [ 49.848049] nixos[2321]: switching to system configuration /nix/store/mqayk7sqni9h069z9q6hl5i722is6510-nixos-system-machine-test machine # [ 49.851948] systemd[1]: Stopped target Remote File Systems. machine # [ 49.854857] systemd[1]: Stopped target Local File Systems. machine # [ 50.353983] systemd[1]: Reload requested from client PID 2321 ('.switch-to-conf') (unit backdoor.service)... machine # [ 50.357160] systemd[1]: Reloading... machine # [ 50.989899] systemd[1]: Reloading finished in 630 ms. machine # [ 51.123716] systemd[620]: Reexecution requested from client PID 2390 ('switch-to-confi')... machine # [ 51.127768] systemd[620]: Reexecuting. machine # [ 51.157791] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.331256] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.340787] systemd[620]: Reload requested from client PID 2390 ('switch-to-confi')... machine # [ 51.343591] systemd[620]: Reloading... machine # [ 51.514688] systemd[620]: Reloading finished in 169 ms. machine # [ 51.521108] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.532101] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 51.535201] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 51.538960] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 51.581744] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 51.586615] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 51.589501] systemd[1]: Stopping Reactivate sysinit units... machine # [ 51.591546] systemd[1]: Reached target Reactivate sysinit units. machine # [ 51.597615] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.601707] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 51.604322] systemd[1]: Reached target Remote File Systems. machine # [ 51.614222] systemd[1]: Reached target Local File Systems. machine # [ 51.616605] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 51.626476] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 51.629822] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 51.632222] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 51.636872] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 51.640970] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.645241] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.951559] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 51.955431] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 51.960476] systemd[1]: Starting linger-users.service... machine # [ 52.015363] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 52.017986] systemd[1]: Finished linger-users.service. machine # [ 52.274128] nixos[2321]: finished switching to system configuration /nix/store/mqayk7sqni9h069z9q6hl5i722is6510-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1, in 3.13 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration dry-activate 2>&1 machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration dry-activate 2>&1, in 0.60 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 53.366854] nixos[2471]: switching to system configuration /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test machine # [ 53.371238] systemd[1]: test.service: Deactivated successfully. machine # [ 53.375050] systemd[1]: Stopped test.service. machine # [ 53.376900] systemd[1]: Stopped target Local File Systems. machine # [ 53.379210] systemd[1]: Stopped target Remote File Systems. machine # [ 53.788722] systemd[1]: Reload requested from client PID 2471 ('.switch-to-conf') (unit backdoor.service)... machine # [ 53.791676] systemd[1]: Reloading... machine # [ 54.346128] systemd[1]: Reloading finished in 551 ms. machine # [ 54.485129] systemd[620]: Reexecution requested from client PID 2540 ('switch-to-confi')... machine # [ 54.489423] systemd[620]: Reexecuting. machine # [ 54.518322] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.680140] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.687776] systemd[620]: Reload requested from client PID 2540 ('switch-to-confi')... machine # [ 54.690618] systemd[620]: Reloading... machine # [ 54.848600] systemd[620]: Reloading finished in 156 ms. machine # [ 54.855050] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.865643] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 54.867458] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 54.872799] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 54.914418] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 54.919837] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 54.921879] systemd[1]: Stopping Reactivate sysinit units... machine # [ 54.924396] systemd[1]: Reached target Reactivate sysinit units. machine # [ 54.927204] systemd[1]: Reached target Local File Systems. machine # [ 54.931446] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 54.934536] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 54.944164] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 54.950482] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 54.953533] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 54.955733] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 54.960456] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 54.965194] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 54.968204] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 54.971179] systemd[1]: Reached target Remote File Systems. machine # [ 55.302255] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 55.305131] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 55.311700] systemd[1]: Starting linger-users.service... machine # [ 55.367991] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 55.371283] systemd[1]: Finished linger-users.service. machine # [ 55.626906] nixos[2471]: finished switching to system configuration /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.75 seconds) machine: must succeed: /nix/store/f6msj92j08lmnmac7crb6xfg325m50c0-separate-activation-script/bin/switch-to-configuration test 2>&1 machine # [ 56.187620] nixos[2611]: switching to system configuration /nix/store/hkbhxgbx3n8sacdxbmc25xb2dwvp2rw5-nixos-system-machine-test machine # [ 56.193361] systemd[1]: Stopped target Remote File Systems. machine # [ 56.194994] systemd[1]: Stopped target Local File Systems. machine # [ 56.654319] systemd[1]: Reload requested from client PID 2611 ('.switch-to-conf') (unit backdoor.service)... machine # [ 56.657047] systemd[1]: Reloading... machine # [ 57.277117] systemd[1]: Reloading finished in 616 ms. machine # [ 57.445579] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 57.449107] systemd[620]: Reexecution requested from client PID 2680 ('switch-to-confi')... machine # [ 57.452134] systemd[620]: Reexecuting. machine # [ 57.478429] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.651907] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.661193] systemd[620]: Reload requested from client PID 2680 ('switch-to-confi')... machine # [ 57.663542] systemd[620]: Reloading... machine # [ 57.814173] systemd[620]: Reloading finished in 147 ms. machine # [ 57.820042] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.831780] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 57.833786] systemd[1]: Stopping Reactivate sysinit units... machine # [ 57.835634] systemd[1]: Reached target Reactivate sysinit units. machine # [ 57.844525] systemd[1]: Reached target Local File Systems. machine # [ 57.847618] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 57.850711] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 57.855142] systemd[1]: Reached target Remote File Systems. machine # [ 57.860135] systemd[1]: Starting linger-users.service... machine # [ 57.863445] systemd[1]: Starting test.service... machine # [ 57.876778] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 57.883509] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 57.886219] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 57.888924] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 57.894550] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 57.897426] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 57.901070] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 57.929922] systemd[1]: Finished test.service. machine # [ 57.962458] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 57.965191] systemd[1]: Finished linger-users.service. machine # [ 58.238830] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 58.241728] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 58.502976] nixos[2611]: finished switching to system configuration /nix/store/hkbhxgbx3n8sacdxbmc25xb2dwvp2rw5-nixos-system-machine-test machine: (finished: must succeed: /nix/store/f6msj92j08lmnmac7crb6xfg325m50c0-separate-activation-script/bin/switch-to-configuration test 2>&1, in 2.88 seconds) machine: must succeed: ! test -e /run/current-system/activate machine: (finished: must succeed: ! test -e /run/current-system/activate, in 0.03 seconds) machine: must succeed: ! test -e /run/current-system/dry-activate machine: (finished: must succeed: ! test -e /run/current-system/dry-activate, in 0.02 seconds) machine: must succeed: ! test -e /run/current-system/bin/switch-to-configuration machine: (finished: must succeed: ! test -e /run/current-system/bin/switch-to-configuration, in 0.02 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/unitWithMultilineValue/bin/switch-to-configuration test 2>&1 machine # [ 59.218664] nixos[2759]: switching to system configuration /nix/store/dznk0f0l2cyjd6v7swmd1fa9r6ck5i2w-nixos-system-machine-test machine # [ 59.222837] systemd[1]: test.service: Deactivated successfully. machine # [ 59.225754] systemd[1]: Stopped test.service. machine # [ 59.227931] systemd[1]: Stopped target Local File Systems. machine # [ 59.230425] systemd[1]: Stopped target Remote File Systems. machine # [ 59.721261] systemd[1]: Reload requested from client PID 2759 ('.switch-to-conf') (unit backdoor.service)... machine # [ 59.724298] systemd[1]: Reloading... machine # [ 60.327402] systemd[1]: Reloading finished in 599 ms. machine # [ 60.459415] systemd[620]: Reexecution requested from client PID 2828 ('switch-to-confi')... machine # [ 60.463643] systemd[620]: Reexecuting. machine # [ 60.493584] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 60.634671] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 60.643960] systemd[620]: Reload requested from client PID 2828 ('switch-to-confi')... machine # [ 60.646885] systemd[620]: Reloading... machine # [ 60.786060] systemd[620]: Reloading finished in 137 ms. machine # [ 60.792106] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 60.803572] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 60.838334] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 60.842267] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 60.845260] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 60.850207] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 60.852643] systemd[1]: Stopping Reactivate sysinit units... machine # [ 60.855782] systemd[1]: Reached target Reactivate sysinit units. machine # [ 60.857645] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 60.863148] systemd[1]: Reached target Remote File Systems. machine # [ 60.868233] systemd[1]: Starting linger-users.service... machine # [ 60.875408] systemd[1]: Reached target Local File Systems. machine # [ 60.895268] systemd[1]: Started test.service. machine # [ 60.904826] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 60.909440] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 60.911937] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 60.919312] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 60.920861] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 60.930614] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 60.940975] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 60.944327] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 60.950553] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 60.952822] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 60.985737] systemd[1]: test.service: Deactivated successfully. machine # [ 61.008154] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 61.010269] systemd[1]: Finished linger-users.service. machine # [ 61.278617] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 61.281438] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 61.540864] nixos[2759]: finished switching to system configuration /nix/store/dznk0f0l2cyjd6v7swmd1fa9r6ck5i2w-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/unitWithMultilineValue/bin/switch-to-configuration test 2>&1, in 2.96 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/unitWithBackslash/bin/switch-to-configuration test 2>&1 machine # [ 62.192762] nixos[2904]: switching to system configuration /nix/store/zwqbq8n9rnann3f8xc2znbh4iin6q8yg-nixos-system-machine-test machine # [ 62.197115] systemd[1]: Stopped target Remote File Systems. machine # [ 62.200098] systemd[1]: Stopped target Local File Systems. machine # [ 62.695223] systemd[1]: Reload requested from client PID 2904 ('.switch-to-conf') (unit backdoor.service)... machine # [ 62.698414] systemd[1]: Reloading... machine # [ 63.372361] systemd[1]: Reloading finished in 670 ms. machine # [ 63.520581] systemd[620]: Reexecution requested from client PID 2973 ('switch-to-confi')... machine # [ 63.526318] systemd[620]: Reexecuting. machine # [ 63.557857] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 63.731917] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 63.741960] systemd[620]: Reload requested from client PID 2973 ('switch-to-confi')... machine # [ 63.744223] systemd[620]: Reloading... machine # [ 63.903957] systemd[620]: Reloading finished in 156 ms. machine # [ 63.909109] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 63.920476] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 63.923174] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 63.926924] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 63.968572] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 63.973399] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 63.975716] systemd[1]: Stopping Reactivate sysinit units... machine # [ 63.977457] systemd[1]: Reached target Reactivate sysinit units. machine # [ 63.984112] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 63.986791] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 63.991460] systemd[1]: Reached target Local File Systems. machine # [ 63.997054] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 64.006153] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 64.008231] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 64.010496] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 64.017702] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 64.021212] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 64.024988] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 64.028783] systemd[1]: Reached target Remote File Systems. machine # [ 64.380416] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 64.383322] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 64.390232] systemd[1]: Starting escaped\x2ddash.service... machine # [ 64.394549] systemd[1]: Starting linger-users.service... machine # [ 64.430305] systemd[1]: Finished escaped\x2ddash.service. machine # [ 64.459654] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 64.463835] systemd[1]: Finished linger-users.service. machine # [ 64.721854] nixos[2904]: finished switching to system configuration /nix/store/zwqbq8n9rnann3f8xc2znbh4iin6q8yg-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/unitWithBackslash/bin/switch-to-configuration test 2>&1, in 3.18 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/unitWithBackslashModified/bin/switch-to-configuration test 2>&1 machine # [ 65.376380] nixos[3046]: switching to system configuration /nix/store/vswgv1j5mg74fwybfs7nhnm8fwz2sfwa-nixos-system-machine-test machine # [ 65.381267] systemd[1]: Stopped target Local File Systems. machine # [ 65.385104] systemd[1]: Stopped target Remote File Systems. machine # [ 65.386784] systemd[1]: escaped\x2ddash.service: Deactivated successfully. machine # [ 65.388922] systemd[1]: Stopped escaped\x2ddash.service. machine # [ 65.899916] systemd[1]: Reload requested from client PID 3046 ('.switch-to-conf') (unit backdoor.service)... machine # [ 65.902838] systemd[1]: Reloading... machine # [ 66.550969] systemd[1]: Reloading finished in 644 ms. machine # [ 66.696285] systemd[620]: Reexecution requested from client PID 3115 ('switch-to-confi')... machine # [ 66.698596] systemd[620]: Reexecuting. machine # [ 66.730948] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 66.907662] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 66.916060] systemd[620]: Reload requested from client PID 3115 ('switch-to-confi')... machine # [ 66.918837] systemd[620]: Reloading... machine # [ 67.085210] systemd[620]: Reloading finished in 163 ms. machine # [ 67.091044] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 67.102564] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 67.104296] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 67.108453] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 67.149466] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 67.154436] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 67.156765] systemd[1]: Stopping Reactivate sysinit units... machine # [ 67.158456] systemd[1]: Reached target Reactivate sysinit units. machine # [ 67.163895] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 67.167594] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 67.175108] systemd[1]: Starting escaped\x2ddash.service... machine # [ 67.177657] systemd[1]: Reached target Local File Systems. machine # [ 67.189693] systemd[1]: Reached target Remote File Systems. machine # [ 67.195107] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 67.200639] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 67.204282] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 67.206482] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 67.212503] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 67.216201] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 67.218446] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 67.242307] systemd[1]: Finished escaped\x2ddash.service. machine # [ 67.539128] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 67.542282] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 67.548576] systemd[1]: Starting linger-users.service... machine # [ 67.606162] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 67.608822] systemd[1]: Finished linger-users.service. machine # [ 67.865098] nixos[3046]: finished switching to system configuration /nix/store/vswgv1j5mg74fwybfs7nhnm8fwz2sfwa-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/unitWithBackslashModified/bin/switch-to-configuration test 2>&1, in 3.14 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/unitStartingWithDash/bin/switch-to-configuration test 2>&1 machine # [ 68.420452] nixos[3188]: switching to system configuration /nix/store/azzfszh9qvdk58y54yg6pv6qjb4pmxvy-nixos-system-machine-test machine # [ 68.424186] systemd[1]: Stopped target Local File Systems. machine # [ 68.427686] systemd[1]: escaped\x2ddash.service: Deactivated successfully. machine # [ 68.429508] systemd[1]: Stopped escaped\x2ddash.service. machine # [ 68.431749] systemd[1]: Stopped target Remote File Systems. machine # [ 68.939682] systemd[1]: Reload requested from client PID 3188 ('.switch-to-conf') (unit backdoor.service)... machine # [ 68.942391] systemd[1]: Reloading... machine # [ 69.573167] systemd[1]: Reloading finished in 627 ms. machine # [ 69.727428] systemd[620]: Reexecution requested from client PID 3257 ('switch-to-confi')... machine # [ 69.730991] systemd[620]: Reexecuting. machine # [ 69.761528] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.930575] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.939496] systemd[620]: Reload requested from client PID 3257 ('switch-to-confi')... machine # [ 69.942265] systemd[620]: Reloading... machine # [ 70.110590] systemd[620]: Reloading finished in 166 ms. machine # [ 70.116103] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 70.127488] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 70.130227] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 70.134430] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 70.175270] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 70.180578] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 70.183236] systemd[1]: Stopping Reactivate sysinit units... machine # [ 70.185432] systemd[1]: Reached target Reactivate sysinit units. machine # [ 70.188588] systemd[1]: Reached target Local File Systems. machine # [ 70.197675] systemd[1]: Reached target Remote File Systems. machine # [ 70.203727] systemd[1]: Starting -.service... machine # [ 70.206792] systemd[1]: Starting linger-users.service... machine # [ 70.213106] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 70.219660] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 70.221322] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 70.226340] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 70.231815] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 70.237200] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 70.241188] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 70.245512] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 70.248397] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 70.287895] systemd[1]: Finished -.service. machine # [ 70.321381] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 70.324226] systemd[1]: Finished linger-users.service. machine # [ 70.604473] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 70.607337] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 70.864426] nixos[3188]: finished switching to system configuration /nix/store/azzfszh9qvdk58y54yg6pv6qjb4pmxvy-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/unitStartingWithDash/bin/switch-to-configuration test 2>&1, in 3.00 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/unitStartingWithDashModified/bin/switch-to-configuration test 2>&1 machine # [ 71.560048] nixos[3330]: switching to system configuration /nix/store/k8ilyiwikcb16bj4v8gay62jf0vjh148-nixos-system-machine-test machine # [ 71.564218] systemd[1]: Stopped target Local File Systems. machine # [ 71.567283] systemd[1]: Stopped target Remote File Systems. machine # [ 72.081470] systemd[1]: Reload requested from client PID 3330 ('.switch-to-conf') (unit backdoor.service)... machine # [ 72.084426] systemd[1]: Reloading... machine # [ 72.697426] systemd[1]: Reloading finished in 609 ms. machine # [ 72.841767] systemd[620]: Reexecution requested from client PID 3399 ('switch-to-confi')... machine # [ 72.846088] systemd[620]: Reexecuting. machine # [ 72.871663] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 73.035957] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 73.044378] systemd[620]: Reload requested from client PID 3399 ('switch-to-confi')... machine # [ 73.046866] systemd[620]: Reloading... machine # [ 73.212923] systemd[620]: Reloading finished in 163 ms. machine # [ 73.219056] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 73.229534] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 73.231395] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 73.236110] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 73.278250] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 73.283066] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 73.285234] systemd[1]: Stopping Reactivate sysinit units... machine # [ 73.287330] systemd[1]: Reached target Reactivate sysinit units. machine # [ 73.293343] systemd[1]: Reloading -.service... machine # [ 73.324482] systemd[1]: Reloaded -.service. machine # [ 73.330599] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 73.333731] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 73.345237] systemd[1]: Reached target Local File Systems. machine # [ 73.346833] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 73.353702] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 73.355267] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 73.357491] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 73.364586] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 73.369156] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 73.371415] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 73.374276] systemd[1]: Reached target Remote File Systems. machine # [ 73.718566] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 73.722465] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 73.727797] systemd[1]: Starting linger-users.service... machine # [ 73.775835] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 73.778297] systemd[1]: Finished linger-users.service. machine # [ 74.038679] nixos[3330]: finished switching to system configuration /nix/store/k8ilyiwikcb16bj4v8gay62jf0vjh148-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/unitStartingWithDashModified/bin/switch-to-configuration test 2>&1, in 3.18 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/unitWithRequirement/bin/switch-to-configuration test 2>&1 machine # [ 74.639923] nixos[3472]: switching to system configuration /nix/store/pk3aj13pdj7h8hm030rs14l0hwzvm40q-nixos-system-machine-test machine # [ 74.645081] systemd[1]: -.service: Deactivated successfully. machine # [ 74.647754] systemd[1]: Stopped -.service. machine # [ 74.650266] systemd[1]: Stopped target Remote File Systems. machine # [ 74.652774] systemd[1]: Stopped target Local File Systems. machine # [ 75.143838] systemd[1]: Reload requested from client PID 3472 ('.switch-to-conf') (unit backdoor.service)... machine # [ 75.146760] systemd[1]: Reloading... machine # [ 75.738171] systemd[1]: Reloading finished in 588 ms. machine # [ 75.891282] systemd[620]: Reexecution requested from client PID 3541 ('switch-to-confi')... machine # [ 75.893916] systemd[620]: Reexecuting. machine # [ 75.923079] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 76.094131] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 76.103571] systemd[620]: Reload requested from client PID 3541 ('switch-to-confi')... machine # [ 76.106097] systemd[620]: Reloading... machine # [ 76.281957] systemd[620]: Reloading finished in 173 ms. machine # [ 76.288052] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 76.299617] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 76.301361] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 76.305733] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 76.347587] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 76.352568] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 76.356260] systemd[1]: Stopping Reactivate sysinit units... machine # [ 76.358502] systemd[1]: Reached target Reactivate sysinit units. machine # [ 76.364118] systemd[1]: Reached target Remote File Systems. machine # [ 76.368148] systemd[1]: Starting linger-users.service... machine # [ 76.372272] systemd[1]: Starting required-service.service... machine # [ 76.377273] systemd[1]: Starting test-service.service... machine # [ 76.386351] systemd[1]: Reached target Local File Systems. machine # [ 76.389226] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 76.395379] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 76.397263] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 76.403291] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 76.409439] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 76.416424] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 76.425155] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 76.432379] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 76.435328] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 76.467241] systemd[1]: Finished required-service.service. machine # [ 76.474141] systemd[1]: Finished test-service.service. machine # [ 76.505229] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 76.508314] systemd[1]: Finished linger-users.service. machine # [ 76.789317] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 76.791312] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 77.048646] nixos[3472]: finished switching to system configuration /nix/store/pk3aj13pdj7h8hm030rs14l0hwzvm40q-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/unitWithRequirement/bin/switch-to-configuration test 2>&1, in 3.00 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/unitWithRequirementModified/bin/switch-to-configuration test 2>&1 machine # [ 77.695974] nixos[3616]: switching to system configuration /nix/store/91dp2ww693l8s7zrsvhj3apgyfq87l05-nixos-system-machine-test machine # [ 77.700205] systemd[1]: Stopped target Local File Systems. machine # [ 77.703654] systemd[1]: Stopped target Remote File Systems. machine # [ 77.707302] systemd[1]: required-service.service: Deactivated successfully. machine # [ 77.709074] systemd[1]: Stopped required-service.service. machine # [ 77.710516] systemd[1]: test-service.service: Deactivated successfully. machine # [ 77.712675] systemd[1]: Stopped test-service.service. machine # [ 78.228362] systemd[1]: Reload requested from client PID 3616 ('.switch-to-conf') (unit backdoor.service)... machine # [ 78.231308] systemd[1]: Reloading... machine # [ 78.842359] systemd[1]: Reloading finished in 607 ms. machine # [ 78.960966] systemd[620]: Reexecution requested from client PID 3685 ('switch-to-confi')... machine # [ 78.965372] systemd[620]: Reexecuting. machine # [ 78.993719] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 79.112064] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 79.121782] systemd[620]: Reload requested from client PID 3685 ('switch-to-confi')... machine # [ 79.124212] systemd[620]: Reloading... machine # [ 79.263908] systemd[620]: Reloading finished in 137 ms. machine # [ 79.271046] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 79.281875] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 79.283710] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 79.288717] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 79.330226] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 79.334830] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 79.337602] systemd[1]: Stopping Reactivate sysinit units... machine # [ 79.339317] systemd[1]: Reached target Reactivate sysinit units. machine # [ 79.346879] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 79.350556] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 79.355479] systemd[1]: Reached target Remote File Systems. machine # [ 79.361151] systemd[1]: Starting linger-users.service... machine # [ 79.365352] systemd[1]: Starting required-service.service... machine # [ 79.371335] systemd[1]: Starting test-service.service... machine # [ 79.388933] systemd[1]: Reached target Local File Systems. machine # [ 79.400350] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 79.406814] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 79.409497] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 79.411794] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 79.416943] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 79.420914] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 79.425080] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 79.451571] systemd[1]: Finished required-service.service. machine # [ 79.459717] systemd[1]: Finished test-service.service. machine # [ 79.490858] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 79.494128] systemd[1]: Finished linger-users.service. machine # [ 79.782485] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 79.786691] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 80.045662] nixos[3616]: finished switching to system configuration /nix/store/91dp2ww693l8s7zrsvhj3apgyfq87l05-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/unitWithRequirementModified/bin/switch-to-configuration test 2>&1, in 3.00 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/unitWithRequirementModifiedNostart/bin/switch-to-configuration test 2>&1 machine # [ 80.756708] nixos[3760]: switching to system configuration /nix/store/h2s6yfzxg2nfjal2dvap8qbc6fjsqndv-nixos-system-machine-test machine # [ 80.760860] systemd[1]: Stopped target Local File Systems. machine # [ 80.764942] systemd[1]: required-service.service: Deactivated successfully. machine # [ 80.766681] systemd[1]: Stopped required-service.service. machine # [ 80.770085] systemd[1]: test-service.service: Deactivated successfully. machine # [ 80.771896] systemd[1]: Stopped test-service.service. machine # [ 80.773503] systemd[1]: Stopped target Remote File Systems. machine # [ 81.269971] systemd[1]: Reload requested from client PID 3760 ('.switch-to-conf') (unit backdoor.service)... machine # [ 81.273076] systemd[1]: Reloading... machine # [ 81.907346] systemd[1]: Reloading finished in 630 ms. machine # [ 82.060709] systemd[620]: Reexecution requested from client PID 3829 ('switch-to-confi')... machine # [ 82.065101] systemd[620]: Reexecuting. machine # [ 82.094487] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 82.264730] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 82.273089] systemd[620]: Reload requested from client PID 3829 ('switch-to-confi')... machine # [ 82.275948] systemd[620]: Reloading... machine # [ 82.444817] systemd[620]: Reloading finished in 167 ms. machine # [ 82.451041] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 82.462710] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 82.464409] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 82.468426] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 82.511737] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 82.517125] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 82.519053] systemd[1]: Stopping Reactivate sysinit units... machine # [ 82.521215] systemd[1]: Reached target Reactivate sysinit units. machine # [ 82.524591] systemd[1]: Reached target Remote File Systems. machine # [ 82.530914] systemd[1]: Starting required-service.service... machine # [ 82.536332] systemd[1]: Reached target Local File Systems. machine # [ 82.537827] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 82.546721] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 82.548300] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 82.550625] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 82.555940] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 82.561780] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 82.565192] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 82.579853] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 82.583367] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 82.609387] systemd[1]: Finished required-service.service. machine # [ 82.917638] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 82.921465] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 82.928122] systemd[1]: Starting linger-users.service... machine # [ 82.931800] systemd[1]: Starting test-service.service... machine # [ 82.968712] systemd[1]: Finished test-service.service. machine # [ 82.996715] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 82.999386] systemd[1]: Finished linger-users.service. machine # [ 83.259868] nixos[3760]: finished switching to system configuration /nix/store/h2s6yfzxg2nfjal2dvap8qbc6fjsqndv-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/unitWithRequirementModifiedNostart/bin/switch-to-configuration test 2>&1, in 3.21 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/unitWithTemplate/bin/switch-to-configuration test 2>&1 machine # [ 83.917919] nixos[3904]: switching to system configuration /nix/store/by7qzfq3vjglj96wpxmpf4gnapy1jxf6-nixos-system-machine-test machine # [ 83.922149] systemd[1]: Stopped target Local File Systems. machine # [ 83.925155] systemd[1]: required-service.service: Deactivated successfully. machine # [ 83.928530] systemd[1]: Stopped required-service.service. machine # [ 83.931396] systemd[1]: test-service.service: Deactivated successfully. machine # [ 83.934215] systemd[1]: Stopped test-service.service. machine # [ 83.935900] systemd[1]: Stopped target Remote File Systems. machine # [ 84.416548] systemd[1]: Reload requested from client PID 3904 ('.switch-to-conf') (unit backdoor.service)... machine # [ 84.419598] systemd[1]: Reloading... machine # [ 85.035396] systemd[1]: Reloading finished in 613 ms. machine # [ 85.182579] systemd[620]: Reexecution requested from client PID 3973 ('switch-to-confi')... machine # [ 85.185239] systemd[620]: Reexecuting. machine # [ 85.219742] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 85.387575] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 85.396068] systemd[620]: Reload requested from client PID 3973 ('switch-to-confi')... machine # [ 85.399124] systemd[620]: Reloading... machine # [ 85.567780] systemd[620]: Reloading finished in 166 ms. machine # [ 85.573070] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 85.584593] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 85.586299] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 85.590553] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 85.633297] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 85.638206] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 85.640341] systemd[1]: Stopping Reactivate sysinit units... machine # [ 85.642275] systemd[1]: Reached target Reactivate sysinit units. machine # [ 85.648664] systemd[1]: Created slice Slice /system/instantiated. machine # [ 85.650869] systemd[1]: Reached target Remote File Systems. machine # [ 85.656328] systemd[1]: Starting instantiated@one.service... machine # [ 85.659290] systemd[1]: Starting instantiated@two.service... machine # [ 85.664108] systemd[1]: Starting linger-users.service... machine # [ 85.679238] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 85.681518] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 85.697125] systemd[1]: Reached target Local File Systems. machine # [ 85.699532] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 85.707483] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 85.709040] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 85.711275] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 85.715802] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 85.719997] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 85.724503] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 85.753984] systemd[1]: Finished instantiated@two.service. machine # [ 85.760124] systemd[1]: Finished instantiated@one.service. machine # [ 85.793261] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 85.795525] systemd[1]: Finished linger-users.service. machine # [ 86.022223] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 86.025099] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 86.285412] nixos[3904]: finished switching to system configuration /nix/store/by7qzfq3vjglj96wpxmpf4gnapy1jxf6-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/unitWithTemplate/bin/switch-to-configuration test 2>&1, in 3.03 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/unitWithTemplateModified/bin/switch-to-configuration test 2>&1 machine # [ 87.029724] nixos[4048]: switching to system configuration /nix/store/zrkc4klwgkjgqxsf2gi2mb5s9iddlkrh-nixos-system-machine-test machine # [ 87.033977] systemd[1]: Stopped target Local File Systems. machine # [ 87.037244] systemd[1]: instantiated@two.service: Deactivated successfully. machine # [ 87.039722] systemd[1]: Stopped instantiated@two.service. machine # [ 87.043246] systemd[1]: Stopped target Remote File Systems. machine # [ 87.045310] systemd[1]: instantiated@one.service: Deactivated successfully. machine # [ 87.047087] systemd[1]: Stopped instantiated@one.service. machine # [ 87.555848] systemd[1]: Reload requested from client PID 4048 ('.switch-to-conf') (unit backdoor.service)... machine # [ 87.558785] systemd[1]: Reloading... machine # [ 88.243381] systemd[1]: Reloading finished in 681 ms. machine # [ 88.395952] systemd[620]: Reexecution requested from client PID 4117 ('switch-to-confi')... machine # [ 88.400362] systemd[620]: Reexecuting. machine # [ 88.428559] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 88.597183] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 88.604990] systemd[620]: Reload requested from client PID 4117 ('switch-to-confi')... machine # [ 88.607527] systemd[620]: Reloading... machine # [ 88.776554] systemd[620]: Reloading finished in 167 ms. machine # [ 88.783083] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 88.794520] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 88.796246] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 88.800437] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 88.840813] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 88.845460] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 88.847789] systemd[1]: Stopping Reactivate sysinit units... machine # [ 88.849914] systemd[1]: Reached target Reactivate sysinit units. machine # [ 88.852572] systemd[1]: Reached target Local File Systems. machine # [ 88.861866] systemd[1]: Starting instantiated@one.service... machine # [ 88.870527] systemd[1]: Starting instantiated@two.service... machine # [ 88.873140] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 88.875932] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 88.887798] systemd[1]: Reached target Remote File Systems. machine # [ 88.897901] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 88.903539] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 88.906560] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 88.909285] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 88.915724] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 88.919665] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 88.922733] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 88.951104] systemd[1]: Finished instantiated@one.service. machine # [ 88.956337] systemd[1]: Finished instantiated@two.service. machine # [ 89.275172] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 89.278111] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 89.284678] systemd[1]: Starting linger-users.service... machine # [ 89.343119] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 89.345647] systemd[1]: Finished linger-users.service. machine # [ 89.602320] nixos[4048]: finished switching to system configuration /nix/store/zrkc4klwgkjgqxsf2gi2mb5s9iddlkrh-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/unitWithTemplateModified/bin/switch-to-configuration test 2>&1, in 3.31 seconds) (finished: subtest: services, in 64.59 seconds) subtest: failing units machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1 machine # [ 90.239450] nixos[4192]: switching to system configuration /nix/store/hhmdwyxhlnnwkb7jlg96vns3jcqzpjj7-nixos-system-machine-test machine # [ 90.243494] systemd[1]: instantiated@two.service: Deactivated successfully. machine # [ 90.247351] systemd[1]: Stopped instantiated@two.service. machine # [ 90.249158] systemd[1]: Stopped target Remote File Systems. machine # [ 90.251343] systemd[1]: Stopped target Local File Systems. machine # [ 90.254215] systemd[1]: instantiated@one.service: Deactivated successfully. machine # [ 90.256466] systemd[1]: Stopped instantiated@one.service. machine # [ 90.713618] systemd[1]: Reload requested from client PID 4192 ('.switch-to-conf') (unit backdoor.service)... machine # [ 90.716604] systemd[1]: Reloading... machine # [ 91.324132] systemd[1]: Reloading finished in 604 ms. machine # [ 91.475285] systemd[620]: Reexecution requested from client PID 4261 ('switch-to-confi')... machine # [ 91.479104] systemd[620]: Reexecuting. machine # [ 91.510728] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 91.684777] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 91.692766] systemd[620]: Reload requested from client PID 4261 ('switch-to-confi')... machine # [ 91.695518] systemd[620]: Reloading... machine # [ 91.866683] systemd[620]: Reloading finished in 169 ms. machine # [ 91.873061] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 91.884478] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 91.886215] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 91.890583] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 91.934318] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 91.939323] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 91.941862] systemd[1]: Stopping Reactivate sysinit units... machine # [ 91.943646] systemd[1]: Reached target Reactivate sysinit units. machine # [ 91.946735] systemd[1]: Reached target Local File Systems. machine # [ 91.950116] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 91.955260] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 91.958554] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 91.962615] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 91.966701] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 91.970572] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 91.975350] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 91.981863] systemd[1]: Reached target Remote File Systems. machine # [ 91.985455] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 91.987747] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 92.300782] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 92.303674] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 92.311172] systemd[1]: Starting linger-users.service... machine # [ 92.315419] systemd[1]: Starting test.service... machine # [ 92.352658] systemd[1]: Finished test.service. machine # [ 92.378901] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 92.382353] systemd[1]: Finished linger-users.service. machine # [ 92.641434] nixos[4192]: finished switching to system configuration /nix/store/hhmdwyxhlnnwkb7jlg96vns3jcqzpjj7-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1, in 3.04 seconds) machine: must fail: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceFailing/bin/switch-to-configuration test 2>&1 machine # [ 93.275665] nixos[4334]: switching to system configuration /nix/store/gnaspq1d4wkrfra8j2kiwhixdz466ylk-nixos-system-machine-test machine # [ 93.279958] systemd[1]: test.service: Deactivated successfully. machine # [ 93.282782] systemd[1]: Stopped test.service. machine # [ 93.285220] systemd[1]: Stopped target Local File Systems. machine # [ 93.287603] systemd[1]: Stopped target Remote File Systems. machine # [ 93.776084] systemd[1]: Reload requested from client PID 4334 ('.switch-to-conf') (unit backdoor.service)... machine # [ 93.778991] systemd[1]: Reloading... machine # [ 94.447435] systemd[1]: Reloading finished in 665 ms. machine # [ 94.595311] systemd[620]: Reexecution requested from client PID 4403 ('switch-to-confi')... machine # [ 94.599645] systemd[620]: Reexecuting. machine # [ 94.630840] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 94.806907] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 94.816794] systemd[620]: Reload requested from client PID 4403 ('switch-to-confi')... machine # [ 94.819187] systemd[620]: Reloading... machine # [ 94.987497] systemd[620]: Reloading finished in 166 ms. machine # [ 94.994075] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 95.005108] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 95.006829] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 95.011802] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 95.054469] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 95.059175] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 95.061569] systemd[1]: Stopping Reactivate sysinit units... machine # [ 95.063694] systemd[1]: Reached target Reactivate sysinit units. machine # [ 95.066285] systemd[1]: Reached target Remote File Systems. machine # [ 95.074159] systemd[1]: Starting linger-users.service... machine # [ 95.078674] systemd[1]: Starting test.service... machine # [ 95.085210] systemd[1]: Reached target Local File Systems. machine # [ 95.094275] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 95.096639] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 95.110738] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 95.118911] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 95.120472] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 95.124456] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 95.129384] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 95.133888] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 95.137435] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 95.157603] systemd[1]: test.service: Main process exited, code=exited, status=1/FAILURE machine # [ 95.163102] systemd[1]: test.service: Failed with result 'exit-code'. machine # [ 95.165451] systemd[1]: Failed to start test.service. machine # [ 95.201750] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 95.204853] systemd[1]: Finished linger-users.service. machine # [ 95.495490] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 95.499471] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 95.827718] nixos[4334]: switching to system configuration /nix/store/gnaspq1d4wkrfra8j2kiwhixdz466ylk-nixos-system-machine-test failed (status 4) machine: (finished: must fail: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceFailing/bin/switch-to-configuration test 2>&1, in 3.18 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1 machine # [ 96.498460] nixos[4477]: switching to system configuration /nix/store/00vj6qadaymp121srpagcr42agbs7yxd-nixos-system-machine-test machine # [ 96.502546] systemd[1]: Stopped target Local File Systems. machine # [ 96.505932] systemd[1]: Stopped target Remote File Systems. machine # [ 97.004278] systemd[1]: Reload requested from client PID 4477 ('.switch-to-conf') (unit backdoor.service)... machine # [ 97.007251] systemd[1]: Reloading... machine # [ 97.677345] systemd[1]: Reloading finished in 666 ms. machine # [ 97.829818] systemd[620]: Reexecution requested from client PID 4546 ('switch-to-confi')... machine # [ 97.834345] systemd[620]: Reexecuting. machine # [ 97.866808] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 98.033105] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 98.042677] systemd[620]: Reload requested from client PID 4546 ('switch-to-confi')... machine # [ 98.045346] systemd[620]: Reloading... machine # [ 98.205137] systemd[620]: Reloading finished in 157 ms. machine # [ 98.209984] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 98.220744] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 98.222548] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 98.227141] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 98.267412] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 98.272182] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 98.274760] systemd[1]: Stopping Reactivate sysinit units... machine # [ 98.276527] systemd[1]: Reached target Reactivate sysinit units. machine # [ 98.279726] systemd[1]: Reached target Remote File Systems. machine # [ 98.287316] systemd[1]: Started autorestart.service. machine # [ 98.291501] systemd[1]: Starting linger-users.service... machine # [ 98.312127] systemd[1]: Reached target Local File Systems. machine # [ 98.315653] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 98.321709] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 98.326246] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 98.329588] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 98.336199] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 98.342072] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 98.344328] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 98.347413] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 98.351069] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 98.375181] systemd[1]: autorestart.service: Deactivated successfully. machine # [ 98.411187] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 98.414306] systemd[1]: Finished linger-users.service. machine # [ 98.692833] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 98.696319] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 98.957889] nixos[4477]: finished switching to system configuration /nix/store/00vj6qadaymp121srpagcr42agbs7yxd-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1, in 3.13 seconds) machine # [ 99.018199] systemd[1]: Stopped autorestart.service. machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1 machine # [ 99.582175] nixos[4624]: switching to system configuration /nix/store/00vj6qadaymp121srpagcr42agbs7yxd-nixos-system-machine-test machine # [ 99.585754] systemd[1]: Stopped target Local File Systems. machine # [ 99.588619] systemd[1]: Stopped target Remote File Systems. machine # [ 99.949786] systemd[1]: Reload requested from client PID 4624 ('.switch-to-conf') (unit backdoor.service)... machine # [ 99.952725] systemd[1]: Reloading... machine # [ 100.477857] systemd[1]: Reloading finished in 523 ms. machine # [ 100.602068] systemd[620]: Reexecution requested from client PID 4693 ('switch-to-confi')... machine # [ 100.606101] systemd[620]: Reexecuting. machine # [ 100.641619] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 100.788987] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 100.798104] systemd[620]: Reload requested from client PID 4693 ('switch-to-confi')... machine # [ 100.800792] systemd[620]: Reloading... machine # [ 100.946377] systemd[620]: Reloading finished in 143 ms. machine # [ 100.952096] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 100.964161] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 100.965847] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 100.969651] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 101.010322] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 101.015074] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 101.017722] systemd[1]: Stopping Reactivate sysinit units... machine # [ 101.019867] systemd[1]: Reached target Reactivate sysinit units. machine # [ 101.023651] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 101.026754] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 101.034624] systemd[1]: Reached target Local File Systems. machine # [ 101.037473] systemd[1]: Reached target Remote File Systems. machine # [ 101.047217] systemd[1]: Started autorestart.service. machine # [ 101.050589] systemd[1]: Starting linger-users.service... machine # [ 101.062304] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 101.070670] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 101.072235] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 101.074472] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 101.078267] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 101.083441] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 101.086321] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 101.119880] systemd[1]: autorestart.service: Deactivated successfully. machine # [ 101.155974] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 101.157846] systemd[1]: Finished linger-users.service. machine # [ 101.419560] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 101.422448] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 101.679933] nixos[4624]: finished switching to system configuration /nix/store/00vj6qadaymp121srpagcr42agbs7yxd-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1, in 2.66 seconds) machine # [ 101.737741] systemd[1]: Stopped autorestart.service. machine: must fail: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/autorestartServiceFailing/bin/switch-to-configuration test 2>&1 machine # [ 102.235205] nixos[4771]: switching to system configuration /nix/store/paczf07i93xd0yvidpgp8ccmyi6jf7fd-nixos-system-machine-test machine # [ 102.238947] systemd[1]: Stopped target Remote File Systems. machine # [ 102.242551] systemd[1]: Stopped target Local File Systems. machine # [ 102.687445] systemd[1]: Reload requested from client PID 4771 ('.switch-to-conf') (unit backdoor.service)... machine # [ 102.690375] systemd[1]: Reloading... machine # [ 103.347381] systemd[1]: Reloading finished in 654 ms. machine # [ 103.495280] systemd[620]: Reexecution requested from client PID 4840 ('switch-to-confi')... machine # [ 103.499275] systemd[620]: Reexecuting. machine # [ 103.529840] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 103.703459] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 103.712054] systemd[620]: Reload requested from client PID 4840 ('switch-to-confi')... machine # [ 103.715316] systemd[620]: Reloading... machine # [ 103.882898] systemd[620]: Reloading finished in 165 ms. machine # [ 103.889083] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 103.899782] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 103.903100] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 103.906327] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 103.945108] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 103.949732] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 103.951835] systemd[1]: Stopping Reactivate sysinit units... machine # [ 103.953334] systemd[1]: Reached target Reactivate sysinit units. machine # [ 103.957637] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 103.960807] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 103.967271] systemd[1]: Reached target Local File Systems. machine # [ 103.968858] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 103.975130] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 103.977694] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 103.980614] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 103.985535] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 103.990288] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 103.993200] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 103.997373] systemd[1]: Reached target Remote File Systems. machine # [ 104.305929] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 104.308782] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 104.316108] systemd[1]: Started autorestart.service. machine # [ 104.318886] systemd[1]: Starting linger-users.service... machine # [ 104.350398] systemd[1]: autorestart.service: Main process exited, code=exited, status=1/FAILURE machine # [ 104.354642] systemd[1]: autorestart.service: Failed with result 'exit-code'. machine # [ 104.386219] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 104.390343] systemd[1]: Finished linger-users.service. machine # [ 104.675345] nixos[4771]: switching to system configuration /nix/store/paczf07i93xd0yvidpgp8ccmyi6jf7fd-nixos-system-machine-test failed (status 4) machine: (finished: must fail: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/autorestartServiceFailing/bin/switch-to-configuration test 2>&1, in 2.94 seconds) (finished: subtest: failing units, in 15.07 seconds) subtest: unit file parser machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 105.210734] nixos[4914]: switching to system configuration /nix/store/5sdxz4fkwdwydf5jc38a3dsc5hiir1bj-nixos-system-machine-test machine # [ 105.214906] systemd[1]: Stopped autorestart.service. machine # [ 105.218421] systemd[1]: Stopped target Local File Systems. machine # [ 105.220851] systemd[1]: Stopped target Remote File Systems. machine # [ 105.633368] systemd[1]: Reload requested from client PID 4914 ('.switch-to-conf') (unit backdoor.service)... machine # [ 105.636188] systemd[1]: Reloading... machine # [ 106.090981] systemd[1]: Reloading finished in 452 ms. machine # [ 106.224147] systemd[620]: Reexecution requested from client PID 4983 ('switch-to-confi')... machine # [ 106.227972] systemd[620]: Reexecuting. machine # [ 106.260451] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 106.425134] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 106.434526] systemd[620]: Reload requested from client PID 4983 ('switch-to-confi')... machine # [ 106.437068] systemd[620]: Reloading... machine # [ 106.589289] systemd[620]: Reloading finished in 149 ms. machine # [ 106.594990] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 106.605447] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 106.608228] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 106.612262] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 106.650126] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 106.654746] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 106.657484] systemd[1]: Stopping Reactivate sysinit units... machine # [ 106.659207] systemd[1]: Reached target Reactivate sysinit units. machine # [ 106.662490] systemd[1]: Reached target Remote File Systems. machine # [ 106.670248] systemd[1]: Reached target Local File Systems. machine # [ 106.672228] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 106.674673] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 106.677381] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 106.683561] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 106.688378] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 106.692686] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 106.698215] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 106.701723] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 106.705611] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 107.042399] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 107.045312] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 107.052245] systemd[1]: Starting linger-users.service... machine # [ 107.055372] systemd[1]: Starting test.service... machine # [ 107.093956] systemd[1]: Finished test.service. machine # [ 107.123105] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 107.126276] systemd[1]: Finished linger-users.service. machine # [ 107.386277] nixos[4914]: finished switching to system configuration /nix/store/5sdxz4fkwdwydf5jc38a3dsc5hiir1bj-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 2.72 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceWithExtraSection/bin/switch-to-configuration test 2>&1 machine # [ 108.049826] nixos[5056]: switching to system configuration /nix/store/dlwjlqi6a04n7y3gi39ga7hcsa8i6577-nixos-system-machine-test machine # [ 108.053810] systemd[1]: Stopped target Local File Systems. machine # [ 108.057179] systemd[1]: Stopped target Remote File Systems. machine # [ 108.542621] systemd[1]: Reload requested from client PID 5056 ('.switch-to-conf') (unit backdoor.service)... machine # [ 108.545699] systemd[1]: Reloading... machine # [ 109.173440] systemd[1]: Reloading finished in 624 ms. machine # [ 109.338946] systemd[620]: Reexecution requested from client PID 5125 ('switch-to-confi')... machine # [ 109.342726] systemd[620]: Reexecuting. machine # [ 109.377386] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 109.555500] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 109.563942] systemd[620]: Reload requested from client PID 5125 ('switch-to-confi')... machine # [ 109.566738] systemd[620]: Reloading... machine # [ 109.731995] systemd[620]: Reloading finished in 163 ms. machine # [ 109.738056] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 109.748593] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 109.751427] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 109.755591] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 109.799197] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 109.803714] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 109.806655] systemd[1]: Stopping Reactivate sysinit units... machine # [ 109.808515] systemd[1]: Reached target Reactivate sysinit units. machine # [ 109.811290] systemd[1]: test.service: Deactivated successfully. machine # [ 109.812894] systemd[1]: Stopped test.service. machine # [ 109.815241] systemd[1]: Stopping test.service... machine # [ 109.818262] systemd[1]: Starting test.service... machine # [ 109.850328] systemd[1]: Finished test.service. machine # [ 109.855104] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 109.858219] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 109.867219] systemd[1]: Reached target Local File Systems. machine # [ 109.868789] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 109.875176] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 109.877527] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 109.881258] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 109.885755] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 109.888951] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 109.893564] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 109.898080] systemd[1]: Reached target Remote File Systems. machine # [ 110.234331] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 110.239528] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 110.244368] systemd[1]: Starting linger-users.service... machine # [ 110.299218] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 110.303280] systemd[1]: Finished linger-users.service. machine # [ 110.562472] nixos[5056]: finished switching to system configuration /nix/store/dlwjlqi6a04n7y3gi39ga7hcsa8i6577-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceWithExtraSection/bin/switch-to-configuration test 2>&1, in 3.17 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceWithExtraSectionOtherName/bin/switch-to-configuration test 2>&1 machine # [ 111.245513] nixos[5198]: switching to system configuration /nix/store/jdrjirxxz4d5dg2nk7babxial9i33lwh-nixos-system-machine-test machine # [ 111.249651] systemd[1]: Stopped target Local File Systems. machine # [ 111.252494] systemd[1]: Stopped target Remote File Systems. machine # [ 111.779474] systemd[1]: Reload requested from client PID 5198 ('.switch-to-conf') (unit backdoor.service)... machine # [ 111.782503] systemd[1]: Reloading... machine # [ 112.415225] systemd[1]: Reloading finished in 629 ms. machine # [ 112.577586] systemd[620]: Reexecution requested from client PID 5267 ('switch-to-confi')... machine # [ 112.579983] systemd[620]: Reexecuting. machine # [ 112.608890] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 112.770194] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 112.778924] systemd[620]: Reload requested from client PID 5267 ('switch-to-confi')... machine # [ 112.781762] systemd[620]: Reloading... machine # [ 112.953960] systemd[620]: Reloading finished in 170 ms. machine # [ 112.959043] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 112.970103] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 112.971883] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 112.976792] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 113.018603] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 113.023486] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 113.025790] systemd[1]: Stopping Reactivate sysinit units... machine # [ 113.027523] systemd[1]: Reached target Reactivate sysinit units. machine # [ 113.031534] systemd[1]: test.service: Deactivated successfully. machine # [ 113.034521] systemd[1]: Stopped test.service. machine # [ 113.035813] systemd[1]: Stopping test.service... machine # [ 113.039426] systemd[1]: Starting test.service... machine # [ 113.066296] systemd[1]: Finished test.service. machine # [ 113.073693] systemd[1]: Reached target Remote File Systems. machine # [ 113.079289] systemd[1]: Reached target Local File Systems. machine # [ 113.080756] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 113.086529] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 113.089821] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 113.092108] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 113.096503] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 113.101206] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 113.105203] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 113.111965] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 113.114707] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 113.437813] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 113.440789] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 113.447516] systemd[1]: Starting linger-users.service... machine # [ 113.504414] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 113.506352] systemd[1]: Finished linger-users.service. machine # [ 113.766921] nixos[5198]: finished switching to system configuration /nix/store/jdrjirxxz4d5dg2nk7babxial9i33lwh-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceWithExtraSectionOtherName/bin/switch-to-configuration test 2>&1, in 3.21 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 114.408794] nixos[5340]: switching to system configuration /nix/store/5sdxz4fkwdwydf5jc38a3dsc5hiir1bj-nixos-system-machine-test machine # [ 114.412690] systemd[1]: Stopped target Remote File Systems. machine # [ 114.415611] systemd[1]: Stopped target Local File Systems. machine # [ 114.853778] systemd[1]: Reload requested from client PID 5340 ('.switch-to-conf') (unit backdoor.service)... machine # [ 114.856690] systemd[1]: Reloading... machine # [ 115.449137] systemd[1]: Reloading finished in 589 ms. machine # [ 115.601598] systemd[620]: Reexecution requested from client PID 5409 ('switch-to-confi')... machine # [ 115.604382] systemd[620]: Reexecuting. machine # [ 115.636563] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 115.796189] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 115.805047] systemd[620]: Reload requested from client PID 5409 ('switch-to-confi')... machine # [ 115.808054] systemd[620]: Reloading... machine # [ 115.974492] systemd[620]: Reloading finished in 165 ms. machine # [ 115.980098] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 115.991497] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 115.993241] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 115.997376] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 116.038863] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 116.043910] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 116.047093] systemd[1]: Stopping Reactivate sysinit units... machine # [ 116.048749] systemd[1]: Reached target Reactivate sysinit units. machine # [ 116.051499] systemd[1]: test.service: Deactivated successfully. machine # [ 116.054144] systemd[1]: Stopped test.service. machine # [ 116.055399] systemd[1]: Stopping test.service... machine # [ 116.057929] systemd[1]: Starting test.service... machine # [ 116.087958] systemd[1]: Finished test.service. machine # [ 116.095213] systemd[1]: Reached target Local File Systems. machine # [ 116.096799] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 116.104381] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 116.107896] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 116.110690] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 116.114973] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 116.120506] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 116.123477] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 116.126265] systemd[1]: Reached target Remote File Systems. machine # [ 116.135310] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 116.138379] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 116.454189] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 116.457117] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 116.463606] systemd[1]: Starting linger-users.service... machine # [ 116.519496] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 116.522311] systemd[1]: Finished linger-users.service. machine # [ 116.782813] nixos[5340]: finished switching to system configuration /nix/store/5sdxz4fkwdwydf5jc38a3dsc5hiir1bj-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 3.01 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceWithInstallSection/bin/switch-to-configuration test 2>&1 machine # [ 117.439266] nixos[5482]: switching to system configuration /nix/store/5gyny11yjg3pyqls5p89d8z125v658h7-nixos-system-machine-test machine # [ 117.443847] systemd[1]: Stopped target Remote File Systems. machine # [ 117.445959] systemd[1]: Stopped target Local File Systems. machine # [ 117.939706] systemd[1]: Reload requested from client PID 5482 ('.switch-to-conf') (unit backdoor.service)... machine # [ 117.942764] systemd[1]: Reloading... machine # [ 118.593371] systemd[1]: Reloading finished in 648 ms. machine # [ 118.756197] systemd[620]: Reexecution requested from client PID 5551 ('switch-to-confi')... machine # [ 118.760098] systemd[620]: Reexecuting. machine # [ 118.790784] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 118.967134] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 118.975771] systemd[620]: Reload requested from client PID 5551 ('switch-to-confi')... machine # [ 118.978111] systemd[620]: Reloading... machine # [ 119.145871] systemd[620]: Reloading finished in 164 ms. machine # [ 119.153061] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 119.164614] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 119.166346] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 119.170648] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 119.213411] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 119.218166] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 119.220874] systemd[1]: Stopping Reactivate sysinit units... machine # [ 119.222637] systemd[1]: Reached target Reactivate sysinit units. machine # [ 119.226875] systemd[1]: Reached target Remote File Systems. machine # [ 119.231748] systemd[1]: Starting linger-users.service... machine # [ 119.238209] systemd[1]: Reached target Local File Systems. machine # [ 119.248637] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 119.255356] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 119.259105] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 119.262234] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 119.267362] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 119.270169] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 119.274282] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 119.279617] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 119.281947] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 119.338273] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 119.341442] systemd[1]: Finished linger-users.service. machine # [ 119.633381] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 119.637266] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 119.897669] nixos[5482]: finished switching to system configuration /nix/store/5gyny11yjg3pyqls5p89d8z125v658h7-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceWithInstallSection/bin/switch-to-configuration test 2>&1, in 3.12 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceWithExtraKey/bin/switch-to-configuration test 2>&1 machine # [ 120.602407] nixos[5622]: switching to system configuration /nix/store/r6i8ixsq75v6xaag4mirhzjnn15nx3g5-nixos-system-machine-test machine # [ 120.606461] systemd[1]: Stopped target Remote File Systems. machine # [ 120.609321] systemd[1]: Stopped target Local File Systems. machine # [ 121.130749] systemd[1]: Reload requested from client PID 5622 ('.switch-to-conf') (unit backdoor.service)... machine # [ 121.133463] systemd[1]: Reloading... machine # [ 121.764967] systemd[1]: Reloading finished in 628 ms. machine # [ 121.915269] systemd[620]: Reexecution requested from client PID 5691 ('switch-to-confi')... machine # [ 121.919103] systemd[620]: Reexecuting. machine # [ 121.950233] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 122.118800] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 122.127623] systemd[620]: Reload requested from client PID 5691 ('switch-to-confi')... machine # [ 122.130218] systemd[620]: Reloading... machine # [ 122.297624] systemd[620]: Reloading finished in 163 ms. machine # [ 122.302076] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 122.312985] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 122.316093] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 122.319819] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 122.363488] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 122.368753] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 122.370844] systemd[1]: Stopping Reactivate sysinit units... machine # [ 122.372833] systemd[1]: Reached target Reactivate sysinit units. machine # [ 122.375594] systemd[1]: test.service: Deactivated successfully. machine # [ 122.378489] systemd[1]: Stopped test.service. machine # [ 122.379759] systemd[1]: Stopping test.service... machine # [ 122.383694] systemd[1]: Starting test.service... machine # [ 122.416120] systemd[1]: Finished test.service. machine # [ 122.421916] systemd[1]: Reached target Local File Systems. machine # [ 122.424475] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 122.430913] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 122.432661] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 122.436959] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 122.441564] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 122.446436] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 122.449273] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 122.454151] systemd[1]: Reached target Remote File Systems. machine # [ 122.456864] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 122.459394] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 122.794768] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 122.797662] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 122.804509] systemd[1]: Starting linger-users.service... machine # [ 122.857967] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 122.861315] systemd[1]: Finished linger-users.service. machine # [ 123.120611] nixos[5622]: finished switching to system configuration /nix/store/r6i8ixsq75v6xaag4mirhzjnn15nx3g5-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceWithExtraKey/bin/switch-to-configuration test 2>&1, in 3.22 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherValue/bin/switch-to-configuration test 2>&1 machine # [ 123.842733] nixos[5764]: switching to system configuration /nix/store/ib31cfdvhj96ynp2pn5paqbyxf12kb76-nixos-system-machine-test machine # [ 123.847978] systemd[1]: Stopped target Remote File Systems. machine # [ 123.851211] systemd[1]: Stopped target Local File Systems. machine # [ 124.333724] systemd[1]: Reload requested from client PID 5764 ('.switch-to-conf') (unit backdoor.service)... machine # [ 124.336655] systemd[1]: Reloading... machine # [ 124.939062] systemd[1]: Reloading finished in 598 ms. machine # [ 125.069187] systemd[620]: Reexecution requested from client PID 5833 ('switch-to-confi')... machine # [ 125.073576] systemd[620]: Reexecuting. machine # [ 125.103329] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 125.244558] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 125.253347] systemd[620]: Reload requested from client PID 5833 ('switch-to-confi')... machine # [ 125.255608] systemd[620]: Reloading... machine # [ 125.409870] systemd[620]: Reloading finished in 151 ms. machine # [ 125.415102] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 125.425414] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 125.428216] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 125.431980] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 125.470695] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 125.475735] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 125.477664] systemd[1]: Stopping Reactivate sysinit units... machine # [ 125.480177] systemd[1]: Reached target Reactivate sysinit units. machine # [ 125.482661] systemd[1]: test.service: Deactivated successfully. machine # [ 125.485541] systemd[1]: Stopped test.service. machine # [ 125.486796] systemd[1]: Stopping test.service... machine # [ 125.489920] systemd[1]: Starting test.service... machine # [ 125.517789] systemd[1]: Finished test.service. machine # [ 125.526128] systemd[1]: Reached target Local File Systems. machine # [ 125.527582] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 125.533560] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 125.536809] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 125.539051] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 125.542826] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 125.545921] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 125.553223] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 125.555339] systemd[1]: Reached target Remote File Systems. machine # [ 125.567105] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 125.569869] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 125.875913] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 125.879291] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 125.885557] systemd[1]: Starting linger-users.service... machine # [ 125.951702] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 125.954975] systemd[1]: Finished linger-users.service. machine # [ 126.214646] nixos[5764]: finished switching to system configuration /nix/store/ib31cfdvhj96ynp2pn5paqbyxf12kb76-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherValue/bin/switch-to-configuration test 2>&1, in 3.09 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherName/bin/switch-to-configuration test 2>&1 machine # [ 126.896614] nixos[5906]: switching to system configuration /nix/store/l66rnck67x70d5lwm8ahni64gb02lnfp-nixos-system-machine-test machine # [ 126.902110] systemd[1]: Stopped target Local File Systems. machine # [ 126.903812] systemd[1]: Stopped target Remote File Systems. machine # [ 127.405346] systemd[1]: Reload requested from client PID 5906 ('.switch-to-conf') (unit backdoor.service)... machine # [ 127.408346] systemd[1]: Reloading... machine # [ 128.059126] systemd[1]: Reloading finished in 647 ms. machine # [ 128.214420] systemd[620]: Reexecution requested from client PID 5975 ('switch-to-confi')... machine # [ 128.216782] systemd[620]: Reexecuting. machine # [ 128.244332] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 128.405794] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 128.414826] systemd[620]: Reload requested from client PID 5975 ('switch-to-confi')... machine # [ 128.417369] systemd[620]: Reloading... machine # [ 128.575793] systemd[620]: Reloading finished in 156 ms. machine # [ 128.582087] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 128.593659] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 128.595335] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 128.599644] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 128.638757] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 128.643758] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 128.646333] systemd[1]: Stopping Reactivate sysinit units... machine # [ 128.647944] systemd[1]: Reached target Reactivate sysinit units. machine # [ 128.650566] systemd[1]: test.service: Deactivated successfully. machine # [ 128.653390] systemd[1]: Stopped test.service. machine # [ 128.654652] systemd[1]: Stopping test.service... machine # [ 128.658366] systemd[1]: Starting test.service... machine # [ 128.688116] systemd[1]: Finished test.service. machine # [ 128.692112] systemd[1]: Reached target Local File Systems. machine # [ 128.696068] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 128.701525] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 128.705111] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 128.707330] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 128.712487] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 128.717273] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 128.719556] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 128.728942] systemd[1]: Reached target Remote File Systems. machine # [ 128.736652] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 128.740205] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 129.062654] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 129.067213] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 129.072370] systemd[1]: Starting linger-users.service... machine # [ 129.131108] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 129.133869] systemd[1]: Finished linger-users.service. machine # [ 129.393715] nixos[5906]: finished switching to system configuration /nix/store/l66rnck67x70d5lwm8ahni64gb02lnfp-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherName/bin/switch-to-configuration test 2>&1, in 3.18 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 130.048802] nixos[6048]: switching to system configuration /nix/store/5sdxz4fkwdwydf5jc38a3dsc5hiir1bj-nixos-system-machine-test machine # [ 130.053167] systemd[1]: Stopped target Remote File Systems. machine # [ 130.056537] systemd[1]: Stopped target Local File Systems. machine # [ 130.487870] systemd[1]: Reload requested from client PID 6048 ('.switch-to-conf') (unit backdoor.service)... machine # [ 130.490824] systemd[1]: Reloading... machine # [ 131.005133] systemd[1]: Reloading finished in 511 ms. machine # [ 131.150922] systemd[620]: Reexecution requested from client PID 6117 ('switch-to-confi')... machine # [ 131.155191] systemd[620]: Reexecuting. machine # [ 131.180514] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 131.335760] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 131.344544] systemd[620]: Reload requested from client PID 6117 ('switch-to-confi')... machine # [ 131.347709] systemd[620]: Reloading... machine # [ 131.502500] systemd[620]: Reloading finished in 152 ms. machine # [ 131.507073] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 131.518108] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 131.521152] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 131.524955] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 131.561914] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 131.566708] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 131.569884] systemd[1]: Stopping Reactivate sysinit units... machine # [ 131.571537] systemd[1]: Reached target Reactivate sysinit units. machine # [ 131.575170] systemd[1]: test.service: Deactivated successfully. machine # [ 131.576766] systemd[1]: Stopped test.service. machine # [ 131.578325] systemd[1]: Stopping test.service... machine # [ 131.581270] systemd[1]: Starting test.service... machine # [ 131.612814] systemd[1]: Finished test.service. machine # [ 131.618109] systemd[1]: Reached target Remote File Systems. machine # [ 131.623209] systemd[1]: Starting linger-users.service... machine # [ 131.629656] systemd[1]: Reached target Local File Systems. machine # [ 131.638195] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 131.644599] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 131.648133] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 131.650915] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 131.657470] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 131.660964] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 131.664249] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 131.673189] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 131.675564] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 131.733122] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 131.735449] systemd[1]: Finished linger-users.service. machine # [ 131.987409] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 131.991281] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 132.251366] nixos[6048]: finished switching to system configuration /nix/store/5sdxz4fkwdwydf5jc38a3dsc5hiir1bj-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 2.86 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceReloadTrigger/bin/switch-to-configuration test 2>&1 machine # [ 132.761334] nixos[6190]: switching to system configuration /nix/store/7b8jk828zfcl2brs27jqdm7p8yq46s7n-nixos-system-machine-test machine # [ 132.765626] systemd[1]: Stopped target Local File Systems. machine # [ 132.768957] systemd[1]: Stopped target Remote File Systems. machine # [ 133.278963] systemd[1]: Reload requested from client PID 6190 ('.switch-to-conf') (unit backdoor.service)... machine # [ 133.281949] systemd[1]: Reloading... machine # [ 133.924349] systemd[1]: Reloading finished in 637 ms. machine # [ 134.059648] systemd[620]: Reexecution requested from client PID 6259 ('switch-to-confi')... machine # [ 134.064225] systemd[620]: Reexecuting. machine # [ 134.091678] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 134.239687] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 134.248222] systemd[620]: Reload requested from client PID 6259 ('switch-to-confi')... machine # [ 134.251358] systemd[620]: Reloading... machine # [ 134.418972] systemd[620]: Reloading finished in 166 ms. machine # [ 134.424041] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 134.435549] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 134.437290] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 134.441699] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 134.481718] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 134.486440] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 134.488416] systemd[1]: Stopping Reactivate sysinit units... machine # [ 134.490788] systemd[1]: Reached target Reactivate sysinit units. machine # [ 134.496427] systemd[1]: Reloading test.service... machine # [ 134.527112] systemd[1]: Reloaded test.service. machine # [ 134.532883] systemd[1]: Reached target Local File Systems. machine # [ 134.535290] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 134.541299] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 134.544122] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 134.546321] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 134.551756] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 134.559055] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 134.561326] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 134.563521] systemd[1]: Reached target Remote File Systems. machine # [ 134.576787] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 134.579750] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 134.903963] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 134.906957] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 134.913366] systemd[1]: Starting linger-users.service... machine # [ 134.973253] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 134.975244] systemd[1]: Finished linger-users.service. machine # [ 135.236618] nixos[6190]: finished switching to system configuration /nix/store/7b8jk828zfcl2brs27jqdm7p8yq46s7n-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 2.98 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModified/bin/switch-to-configuration test 2>&1 machine # [ 135.856637] nixos[6332]: switching to system configuration /nix/store/3brfcff600cdc3imbl8vinh8q3chn33i-nixos-system-machine-test machine # [ 135.862209] systemd[1]: Stopped target Local File Systems. machine # [ 135.863871] systemd[1]: Stopped target Remote File Systems. machine # [ 136.372403] systemd[1]: Reload requested from client PID 6332 ('.switch-to-conf') (unit backdoor.service)... machine # [ 136.375397] systemd[1]: Reloading... machine # [ 137.045138] systemd[1]: Reloading finished in 667 ms. machine # [ 137.179993] systemd[620]: Reexecution requested from client PID 6401 ('switch-to-confi')... machine # [ 137.182442] systemd[620]: Reexecuting. machine # [ 137.208277] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 137.368559] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 137.376976] systemd[620]: Reload requested from client PID 6401 ('switch-to-confi')... machine # [ 137.379821] systemd[620]: Reloading... machine # [ 137.529111] systemd[620]: Reloading finished in 147 ms. machine # [ 137.536078] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 137.547695] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 137.549373] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 137.554908] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 137.592248] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 137.597067] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 137.600117] systemd[1]: Stopping Reactivate sysinit units... machine # [ 137.601992] systemd[1]: Reached target Reactivate sysinit units. machine # [ 137.609943] systemd[1]: Reloading test.service... machine # [ 137.635995] systemd[1]: Reloaded test.service. machine # [ 137.639502] systemd[1]: Reached target Remote File Systems. machine # [ 137.651929] systemd[1]: Starting linger-users.service... machine # [ 137.654604] systemd[1]: Reached target Local File Systems. machine # [ 137.661093] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 137.663423] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 137.675873] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 137.679876] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 137.683464] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 137.685762] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 137.689945] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 137.693814] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 137.696327] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 137.746977] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 137.750124] systemd[1]: Finished linger-users.service. machine # [ 138.065419] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 138.068370] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 138.324863] nixos[6332]: finished switching to system configuration /nix/store/3brfcff600cdc3imbl8vinh8q3chn33i-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModified/bin/switch-to-configuration test 2>&1, in 3.08 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedAndSomethingElse/bin/switch-to-configuration test 2>&1 machine # [ 138.954509] nixos[6474]: switching to system configuration /nix/store/gzb0fknddv97ggfgfh4kp06nfrj39q7z-nixos-system-machine-test machine # [ 138.958513] systemd[1]: Stopped target Remote File Systems. machine # [ 138.961445] systemd[1]: Stopped target Local File Systems. machine # [ 139.440458] systemd[1]: Reload requested from client PID 6474 ('.switch-to-conf') (unit backdoor.service)... machine # [ 139.443461] systemd[1]: Reloading... machine # [ 140.051364] systemd[1]: Reloading finished in 605 ms. machine # [ 140.202048] systemd[620]: Reexecution requested from client PID 6543 ('switch-to-confi')... machine # [ 140.206224] systemd[620]: Reexecuting. machine # [ 140.233888] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 140.385338] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 140.394925] systemd[620]: Reload requested from client PID 6543 ('switch-to-confi')... machine # [ 140.397494] systemd[620]: Reloading... machine # [ 140.552123] systemd[620]: Reloading finished in 151 ms. machine # [ 140.557057] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 140.567605] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 140.570366] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 140.574330] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 140.612975] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 140.617643] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 140.619937] systemd[1]: Stopping Reactivate sysinit units... machine # [ 140.622467] systemd[1]: Reached target Reactivate sysinit units. machine # [ 140.624617] systemd[1]: test.service: Deactivated successfully. machine # [ 140.627512] systemd[1]: Stopped test.service. machine # [ 140.628838] systemd[1]: Stopping test.service... machine # [ 140.632476] systemd[1]: Starting test.service... machine # [ 140.658115] systemd[1]: Finished test.service. machine # [ 140.662055] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 140.665244] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 140.672257] systemd[1]: Reached target Local File Systems. machine # [ 140.674994] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 140.678504] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 140.681808] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 140.685657] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 140.691194] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 140.695547] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 140.698422] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 140.706711] systemd[1]: Reached target Remote File Systems. machine # [ 141.015247] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 141.018107] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 141.024842] systemd[1]: Starting linger-users.service... machine # [ 141.085726] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 141.087605] systemd[1]: Finished linger-users.service. machine # [ 141.343932] nixos[6474]: finished switching to system configuration /nix/store/gzb0fknddv97ggfgfh4kp06nfrj39q7z-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedAndSomethingElse/bin/switch-to-configuration test 2>&1, in 3.02 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedSomethingElse/bin/switch-to-configuration test 2>&1 machine # [ 141.967181] nixos[6616]: switching to system configuration /nix/store/r6i8ixsq75v6xaag4mirhzjnn15nx3g5-nixos-system-machine-test machine # [ 141.970821] systemd[1]: Stopped target Remote File Systems. machine # [ 141.973791] systemd[1]: Stopped target Local File Systems. machine # [ 142.381060] systemd[1]: Reload requested from client PID 6616 ('.switch-to-conf') (unit backdoor.service)... machine # [ 142.383935] systemd[1]: Reloading... machine # [ 142.893863] systemd[1]: Reloading finished in 507 ms. machine # [ 143.017756] systemd[620]: Reexecution requested from client PID 6685 ('switch-to-confi')... machine # [ 143.020250] systemd[620]: Reexecuting. machine # [ 143.050415] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 143.211209] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 143.219132] systemd[620]: Reload requested from client PID 6685 ('switch-to-confi')... machine # [ 143.222291] systemd[620]: Reloading... machine # [ 143.357354] systemd[620]: Reloading finished in 133 ms. machine # [ 143.364079] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 143.374503] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 143.377247] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 143.380757] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 143.419212] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 143.424459] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 143.426659] systemd[1]: Stopping Reactivate sysinit units... machine # [ 143.428575] systemd[1]: Reached target Reactivate sysinit units. machine # [ 143.431330] systemd[1]: Reached target Local File Systems. machine # [ 143.434894] systemd[1]: Reached target Remote File Systems. machine # [ 143.439546] systemd[1]: Starting linger-users.service... machine # [ 143.442865] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 143.450303] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 143.454147] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 143.457660] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 143.463244] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 143.468687] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 143.470937] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 143.482108] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 143.484381] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 143.548370] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 143.551560] systemd[1]: Finished linger-users.service. machine # [ 143.824702] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 143.827600] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 144.090673] nixos[6616]: finished switching to system configuration /nix/store/r6i8ixsq75v6xaag4mirhzjnn15nx3g5-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedSomethingElse/bin/switch-to-configuration test 2>&1, in 2.75 seconds) (finished: subtest: unit file parser, in 39.42 seconds) subtest: restart and reload by activation script machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1 machine # [ 144.685234] nixos[6756]: switching to system configuration /nix/store/mqayk7sqni9h069z9q6hl5i722is6510-nixos-system-machine-test machine # [ 144.690820] systemd[1]: Stopped target Local File Systems. machine # [ 144.692502] systemd[1]: Stopped target Remote File Systems. machine # [ 145.128729] systemd[1]: Reload requested from client PID 6756 ('.switch-to-conf') (unit backdoor.service)... machine # [ 145.131688] systemd[1]: Reloading... machine # [ 145.724201] systemd[1]: Reloading finished in 589 ms. machine # [ 145.882366] systemd[620]: Reexecution requested from client PID 6825 ('switch-to-confi')... machine # [ 145.886287] systemd[620]: Reexecuting. machine # [ 145.913570] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 146.082361] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 146.090489] systemd[620]: Reload requested from client PID 6825 ('switch-to-confi')... machine # [ 146.093468] systemd[620]: Reloading... machine # [ 146.262271] systemd[620]: Reloading finished in 167 ms. machine # [ 146.268055] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 146.279099] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 146.280890] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 146.285904] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 146.326924] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 146.331546] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 146.333912] systemd[1]: Stopping Reactivate sysinit units... machine # [ 146.336072] systemd[1]: Reached target Reactivate sysinit units. machine # [ 146.342955] systemd[1]: Reached target Remote File Systems. machine # [ 146.345849] systemd[1]: Reached target Local File Systems. machine # [ 146.351339] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 146.355105] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 146.361886] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 146.369397] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 146.370941] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 146.376402] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 146.380500] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 146.383719] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 146.386368] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 146.713856] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 146.716122] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 146.722460] systemd[1]: Starting linger-users.service... machine # [ 146.778319] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 146.780975] systemd[1]: Finished linger-users.service. machine # [ 147.041389] nixos[6756]: finished switching to system configuration /nix/store/mqayk7sqni9h069z9q6hl5i722is6510-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1, in 2.95 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1 machine # [ 147.614680] nixos[6896]: switching to system configuration /nix/store/y7h6gfqg1rn39z4xpapcsvpj5nzyxcj2-nixos-system-machine-test machine # [ 147.618784] systemd[1]: Stopped target Remote File Systems. machine # [ 147.621378] systemd[1]: test.service: Deactivated successfully. machine # [ 147.625049] systemd[1]: Stopped test.service. machine # [ 147.626581] systemd[1]: Stopped target Local File Systems. machine # [ 148.067788] systemd[1]: Reload requested from client PID 6896 ('.switch-to-conf') (unit backdoor.service)... machine # [ 148.070606] systemd[1]: Reloading... machine # [ 148.657138] systemd[1]: Reloading finished in 583 ms. machine # [ 148.804580] systemd[620]: Reexecution requested from client PID 6967 ('switch-to-confi')... machine # [ 148.811052] systemd[620]: Reexecuting. machine # [ 148.843615] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 149.008363] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 149.016594] systemd[620]: Reload requested from client PID 6967 ('switch-to-confi')... machine # [ 149.018862] systemd[620]: Reloading... machine # [ 149.181124] systemd[620]: Reloading finished in 159 ms. machine # [ 149.188051] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 149.199716] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 149.201394] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 149.205703] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 149.248717] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 149.253369] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 149.256210] systemd[1]: Stopping Reactivate sysinit units... machine # [ 149.257846] systemd[1]: Reached target Reactivate sysinit units. machine # [ 149.265104] systemd[1]: Starting simple-restart-service.service... machine # [ 149.268884] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 149.272192] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 149.279625] systemd[1]: Reached target Local File Systems. machine # [ 149.286366] systemd[1]: Created slice Slice /system/templated-reload-triggers. machine # [ 149.289735] systemd[1]: Created slice Slice /system/templated-reload-triggers-and-restart. machine # [ 149.293109] systemd[1]: Reached target Remote File Systems. machine # [ 149.298230] systemd[1]: Starting linger-users.service... machine # [ 149.301362] systemd[1]: Starting reload-triggers-and-restart.service... machine # [ 149.307619] systemd[1]: Starting reload-triggers.service... machine # [ 149.313498] systemd[1]: Starting templated-reload-triggers-and-restart@instance.service... machine # [ 149.320565] systemd[1]: Starting templated-reload-triggers@instance.service... machine # [ 149.332107] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 149.341692] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 149.347252] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 149.354388] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 149.361284] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 149.372262] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 149.380212] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 149.388250] systemd[1]: Created slice Slice /system/templated-no-restart-service. machine # [ 149.400288] systemd[1]: Created slice Slice /system/templated-simple-restart-service. machine # [ 149.418932] systemd[1]: Created slice Slice /system/templated-simple-reload-service. machine # [ 149.422463] systemd[1]: Finished simple-restart-service.service. machine # [ 149.456451] systemd[1]: Finished reload-triggers-and-restart.service. machine # [ 149.465401] systemd[1]: Finished templated-reload-triggers@instance.service. machine # [ 149.470099] systemd[1]: Finished reload-triggers.service. machine # [ 149.474298] systemd[1]: Finished templated-reload-triggers-and-restart@instance.service. machine # [ 149.490368] systemd[1]: Created slice Slice /system/templated-reload-triggers-and-restart-by-as. machine # [ 149.494407] systemd[1]: Created slice Slice /system/templated-simple-service. machine # [ 149.515774] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 149.519752] systemd[1]: Finished linger-users.service. machine # [ 149.798184] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 149.801078] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 149.808114] systemd[1]: Starting no-restart-service.service... machine # [ 149.812255] systemd[1]: Starting reload-triggers-and-restart-by-as.service... machine # [ 149.818597] systemd[1]: Starting simple-reload-service.service... machine # [ 149.822284] systemd[1]: Starting simple-service.service... machine # [ 149.828604] systemd[1]: Starting templated-no-restart-service@instance.service... machine # [ 149.836332] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 149.846684] systemd[1]: Starting templated-simple-reload-service@instance.service... machine # [ 149.855244] systemd[1]: Starting templated-simple-restart-service@instance.service... machine # [ 149.866097] systemd[1]: Starting templated-simple-service@instance.service... machine # [ 149.930306] systemd[1]: Finished no-restart-service.service. machine # [ 149.938326] systemd[1]: Finished reload-triggers-and-restart-by-as.service. machine # [ 149.943433] systemd[1]: Finished simple-reload-service.service. machine # [ 149.952280] systemd[1]: Finished simple-service.service. machine # [ 149.955737] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 149.964292] systemd[1]: Finished templated-simple-reload-service@instance.service. machine # [ 149.967924] systemd[1]: Finished templated-no-restart-service@instance.service. machine # [ 149.973795] systemd[1]: Finished templated-simple-restart-service@instance.service. machine # [ 149.982214] systemd[1]: Finished templated-simple-service@instance.service. machine # [ 150.242397] nixos[6896]: finished switching to system configuration /nix/store/y7h6gfqg1rn39z4xpapcsvpj5nzyxcj2-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1, in 3.20 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1 machine # [ 150.993171] nixos[7066]: switching to system configuration /nix/store/y7h6gfqg1rn39z4xpapcsvpj5nzyxcj2-nixos-system-machine-test machine # [ 150.997153] systemd[1]: Stopped target Local File Systems. machine # [ 150.999916] systemd[1]: Stopped target Remote File Systems. machine # [ 151.502908] systemd[1]: Reload requested from client PID 7066 ('.switch-to-conf') (unit backdoor.service)... machine # [ 151.505823] systemd[1]: Reloading... machine # [ 152.126485] systemd[1]: Reloading finished in 617 ms. machine # [ 152.264137] systemd[620]: Reexecution requested from client PID 7137 ('switch-to-confi')... machine # [ 152.268350] systemd[620]: Reexecuting. machine # [ 152.298608] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 152.463988] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 152.473771] systemd[620]: Reload requested from client PID 7137 ('switch-to-confi')... machine # [ 152.476198] systemd[620]: Reloading... machine # [ 152.627535] systemd[620]: Reloading finished in 149 ms. machine # [ 152.634105] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 152.644709] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 152.647437] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 152.651209] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 152.690917] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 152.695666] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 152.697994] systemd[1]: Stopping Reactivate sysinit units... machine # [ 152.700189] systemd[1]: Reached target Reactivate sysinit units. machine # [ 152.707397] systemd[1]: Reloading reload-triggers-and-restart.service... machine # [ 152.714445] systemd[1]: Reloading reload-triggers.service... machine # [ 152.722284] systemd[1]: Reloading simple-reload-service.service... machine # [ 152.730378] systemd[1]: Reloading templated-reload-triggers-and-restart@instance.service... machine # [ 152.741310] systemd[1]: Reloading templated-reload-triggers@instance.service... machine # [ 152.750216] systemd[1]: Reloading templated-simple-reload-service@instance.service... machine # [ 152.789249] systemd[1]: Reloaded reload-triggers-and-restart.service. machine # [ 152.799712] systemd[1]: Reloaded templated-reload-triggers-and-restart@instance.service. machine # [ 152.804983] systemd[1]: Reloaded reload-triggers.service. machine # [ 152.808139] systemd[1]: Reloaded simple-reload-service.service. machine # [ 152.824309] systemd[1]: Reloaded templated-reload-triggers@instance.service. machine # [ 152.827784] systemd[1]: Reloaded templated-simple-reload-service@instance.service. machine # [ 152.832555] systemd[1]: reload-triggers-and-restart-by-as.service: Deactivated successfully. machine # [ 152.835411] systemd[1]: Stopped reload-triggers-and-restart-by-as.service. machine # [ 152.838208] systemd[1]: Stopping reload-triggers-and-restart-by-as.service... machine # [ 152.841910] systemd[1]: Starting reload-triggers-and-restart-by-as.service... machine # [ 152.845580] systemd[1]: simple-restart-service.service: Deactivated successfully. machine # [ 152.847806] systemd[1]: Stopped simple-restart-service.service. machine # [ 152.849891] systemd[1]: Stopping simple-restart-service.service... machine # [ 152.855464] systemd[1]: Starting simple-restart-service.service... machine # [ 152.860153] systemd[1]: simple-service.service: Deactivated successfully. machine # [ 152.863228] systemd[1]: Stopped simple-service.service. machine # [ 152.864654] systemd[1]: Stopping simple-service.service... machine # [ 152.867528] systemd[1]: Starting simple-service.service... machine # [ 152.871876] systemd[1]: templated-reload-triggers-and-restart-by-as@instance.service: Deactivated successfully. machine # [ 152.875528] systemd[1]: Stopped templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 152.879256] systemd[1]: Stopping templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 152.883922] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 152.889102] systemd[1]: templated-simple-restart-service@instance.service: Deactivated successfully. machine # [ 152.891486] systemd[1]: Stopped templated-simple-restart-service@instance.service. machine # [ 152.893489] systemd[1]: Stopping templated-simple-restart-service@instance.service... machine # [ 152.902371] systemd[1]: Starting templated-simple-restart-service@instance.service... machine # [ 152.909176] systemd[1]: templated-simple-service@instance.service: Deactivated successfully. machine # [ 152.911437] systemd[1]: Stopped templated-simple-service@instance.service. machine # [ 152.913787] systemd[1]: Stopping templated-simple-service@instance.service... machine # [ 152.917926] systemd[1]: Starting templated-simple-service@instance.service... machine # [ 152.962432] systemd[1]: Finished simple-service.service. machine # [ 152.967854] systemd[1]: Finished simple-restart-service.service. machine # [ 152.970252] systemd[1]: Finished reload-triggers-and-restart-by-as.service. machine # [ 152.979393] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 152.986466] systemd[1]: Finished templated-simple-restart-service@instance.service. machine # [ 152.991828] systemd[1]: Finished templated-simple-service@instance.service. machine # [ 152.997843] systemd[1]: Reached target Remote File Systems. machine # [ 153.003277] systemd[1]: Reached target Local File Systems. machine # [ 153.004733] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 153.009510] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 153.013678] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 153.015893] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 153.020858] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 153.024826] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 153.028342] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 153.035940] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 153.038960] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 153.318398] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 153.320669] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 153.327112] systemd[1]: Starting linger-users.service... machine # [ 153.385658] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 153.388318] systemd[1]: Finished linger-users.service. machine # [ 153.645293] nixos[7066]: finished switching to system configuration /nix/store/y7h6gfqg1rn39z4xpapcsvpj5nzyxcj2-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1, in 3.40 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script-modified/bin/switch-to-configuration test 2>&1 machine # [ 154.418150] nixos[7232]: switching to system configuration /nix/store/508dsfprr6pcif0nk52jx74571a3ds1p-nixos-system-machine-test machine # [ 154.422167] systemd[1]: Stopped target Local File Systems. machine # [ 154.425206] systemd[1]: Stopped target Remote File Systems. machine # [ 154.985176] systemd[1]: Reload requested from client PID 7232 ('.switch-to-conf') (unit backdoor.service)... machine # [ 154.988325] systemd[1]: Reloading... machine # [ 155.658378] systemd[1]: Reloading finished in 666 ms. machine # [ 155.812765] systemd[620]: Reexecution requested from client PID 7303 ('switch-to-confi')... machine # [ 155.815693] systemd[620]: Reexecuting. machine # [ 155.846889] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 156.013247] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 156.022668] systemd[620]: Reload requested from client PID 7303 ('switch-to-confi')... machine # [ 156.025503] systemd[620]: Reloading... machine # [ 156.191540] systemd[620]: Reloading finished in 163 ms. machine # [ 156.196050] systemd[620]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 156.207470] systemd[620]: Stopped Run user-specific NixOS activation. machine # [ 156.209188] systemd[620]: Stopping Run user-specific NixOS activation... machine # [ 156.213507] systemd[620]: Starting Run user-specific NixOS activation... machine # [ 156.256065] systemd[620]: Finished Run user-specific NixOS activation. machine # [ 156.260868] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 156.263490] systemd[1]: Stopping Reactivate sysinit units... machine # [ 156.265191] systemd[1]: Reached target Reactivate sysinit units. machine # [ 156.272184] systemd[1]: Reloading reload-triggers.service... machine # [ 156.279973] systemd[1]: Reloading simple-reload-service.service... machine # [ 156.287106] systemd[1]: Reloading templated-reload-triggers@instance.service... machine # [ 156.295951] systemd[1]: Reloading templated-simple-reload-service@instance.service... machine # [ 156.336317] systemd[1]: Reloaded simple-reload-service.service. machine # [ 156.341169] systemd[1]: Reloaded reload-triggers.service. machine # [ 156.347099] systemd[1]: Reloaded templated-reload-triggers@instance.service. machine # [ 156.353121] systemd[1]: Reloaded templated-simple-reload-service@instance.service. machine # [ 156.357367] systemd[1]: reload-triggers-and-restart-by-as.service: Deactivated successfully. machine # [ 156.361234] systemd[1]: Stopped reload-triggers-and-restart-by-as.service. machine # [ 156.363107] systemd[1]: Stopping reload-triggers-and-restart-by-as.service... machine # [ 156.366184] systemd[1]: Starting reload-triggers-and-restart-by-as.service... machine # [ 156.370168] systemd[1]: reload-triggers-and-restart.service: Deactivated successfully. machine # [ 156.372160] systemd[1]: Stopped reload-triggers-and-restart.service. machine # [ 156.373828] systemd[1]: Stopping reload-triggers-and-restart.service... machine # [ 156.378804] systemd[1]: Starting reload-triggers-and-restart.service... machine # [ 156.383105] systemd[1]: simple-restart-service.service: Deactivated successfully. machine # [ 156.385488] systemd[1]: Stopped simple-restart-service.service. machine # [ 156.387568] systemd[1]: Stopping simple-restart-service.service... machine # [ 156.391320] systemd[1]: Starting simple-restart-service.service... machine # [ 156.396492] systemd[1]: simple-service.service: Deactivated successfully. machine # [ 156.399212] systemd[1]: Stopped simple-service.service. machine # [ 156.401349] systemd[1]: Stopping simple-service.service... machine # [ 156.408289] systemd[1]: Starting simple-service.service... machine # [ 156.409749] systemd[1]: templated-reload-triggers-and-restart-by-as@instance.service: Deactivated successfully. machine # [ 156.413217] systemd[1]: Stopped templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 156.415794] systemd[1]: Stopping templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 156.423748] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 156.431470] systemd[1]: templated-reload-triggers-and-restart@instance.service: Deactivated successfully. machine # [ 156.442247] systemd[1]: Stopped templated-reload-triggers-and-restart@instance.service. machine # [ 156.445456] systemd[1]: Stopping templated-reload-triggers-and-restart@instance.service... machine # [ 156.450655] systemd[1]: Starting templated-reload-triggers-and-restart@instance.service... machine # [ 156.455933] systemd[1]: templated-simple-restart-service@instance.service: Deactivated successfully. machine # [ 156.463898] systemd[1]: Stopped templated-simple-restart-service@instance.service. machine # [ 156.465890] systemd[1]: Stopping templated-simple-restart-service@instance.service... machine # [ 156.473242] systemd[1]: Starting templated-simple-restart-service@instance.service... machine # [ 156.475769] systemd[1]: templated-simple-service@instance.service: Deactivated successfully. machine # [ 156.481864] systemd[1]: Stopped templated-simple-service@instance.service. machine # [ 156.489481] systemd[1]: Stopping templated-simple-service@instance.service... machine # [ 156.494493] systemd[1]: Starting templated-simple-service@instance.service... machine # [ 156.525244] systemd[1]: Finished reload-triggers-and-restart.service. machine # [ 156.533180] systemd[1]: Finished reload-triggers-and-restart-by-as.service. machine # [ 156.538965] systemd[1]: Finished simple-restart-service.service. machine # [ 156.549312] systemd[1]: Finished simple-service.service. machine # [ 156.552950] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 156.557240] systemd[1]: Finished templated-reload-triggers-and-restart@instance.service. machine # [ 156.565547] systemd[1]: Finished templated-simple-restart-service@instance.service. machine # [ 156.570392] systemd[1]: Finished templated-simple-service@instance.service. machine # [ 156.577363] systemd[1]: Reached target Local File Systems. machine # [ 156.579985] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 156.582359] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 156.586813] systemd[1]: Reached target Remote File Systems. machine # [ 156.592118] systemd[1]: Starting linger-users.service... machine # [ 156.607747] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 156.615781] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 156.617420] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 156.619624] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 156.624830] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 156.629554] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 156.631935] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 156.690324] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 156.694719] systemd[1]: Finished linger-users.service. machine # [ 156.979492] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 156.982388] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 157.238968] nixos[7232]: finished switching to system configuration /nix/store/508dsfprr6pcif0nk52jx74571a3ds1p-nixos-system-machine-test machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script-modified/bin/switch-to-configuration test 2>&1, in 3.59 seconds) machine: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration dry-activate 2>&1 machine: (finished: must succeed: /nix/store/4m0sy99jq0lkpwmfnaq0hn10ci3j6xfq-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration dry-activate 2>&1, in 0.84 seconds) (finished: subtest: restart and reload by activation script, in 13.98 seconds) (finished: run the VM test script, in 159.29 seconds) test script finished in 159.33s cleanup kill QemuMachine (pid 45) machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/b5bpi6zfajzzrwwpgba2q6li3nnya4bs-python3-3.14.7/bin/python3.14) (finished: cleanup, in 0.16 seconds) additionally exposed symbols: machine, other, vlan1, 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