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 # Disk image does not exist, creating the virtualisation disk image... machine: QEMU running (pid 45) machine # Formatting '/build/vm-state-machine/tmp.GZpM3ozYLa', 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: d3eae1cc-a688-4195-be7e-c696f63c47e7 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 # Starting virtiofs daemons... machine # [2026-09-14T03:03:59Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-14T03:03:59Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-14T03:03:59Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-14T03:03:59Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-14T03:03:59Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-14T03:03:59Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-14T03:03:59Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-14T03:03:59Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-14T03:03:59Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-14T03:04:00Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-14T03:04:00Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-14T03:04:00Z INFO virtiofsd] Client connected, servicing requests 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:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB00 machine # Press Ctrl-B to configure iPXE (PCI 00:05.0)... machine # machine # machine # Booting from ROM... machine # Probing EDD (edd=off to disable)... o[ 0.000000] Linux version 6.18.51 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 11 09:49:46 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/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/init regInfo=/nix/store/db5xmkvnyxhf3lh2h7r4i09pmwydi0pn-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.000001] kvm-clock: using sched offset of 441271154 cycles machine # [ 0.000003] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns machine # [ 0.000006] tsc: Detected 2400.012 MHz processor machine # [ 0.000817] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 machine # [ 0.000843] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs machine # [ 0.000845] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT machine # [ 0.002771] found SMP MP-table at [mem 0x000f5450-0x000f545f] machine # [ 0.002783] Using GB pages for direct mapping machine # [ 0.002836] RAMDISK: [mem 0x3e41c000-0x3ffcffff] machine # [ 0.002846] ACPI: Early table checksum verification disabled machine # [ 0.002849] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) machine # [ 0.002853] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002857] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002864] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002866] ACPI: FACS 0x000000003FFE0000 000040 machine # [ 0.002868] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002869] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002871] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002872] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002874] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d] machine # [ 0.002875] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219] machine # [ 0.002875] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f] machine # [ 0.002876] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385] machine # [ 0.002876] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd] machine # [ 0.002877] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9] machine # [ 0.002877] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421] machine # [ 0.003104] No NUMA configuration found machine # [ 0.003105] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff] machine # [ 0.003108] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff] machine # [ 0.005502] Zone ranges: machine # [ 0.005502] DMA [mem 0x0000000000001000-0x0000000000ffffff] machine # [ 0.005504] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff] machine # [ 0.005505] Normal empty machine # [ 0.005506] Device empty machine # [ 0.005506] Movable zone start for each node machine # [ 0.005507] Early memory node ranges machine # [ 0.005507] node 0: [mem 0x0000000000001000-0x000000000009efff] machine # [ 0.005508] node 0: [mem 0x0000000000100000-0x000000003ffd7fff] machine # [ 0.005509] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff] machine # [ 0.005535] On node 0, zone DMA: 1 pages in unavailable ranges machine # [ 0.005791] On node 0, zone DMA: 97 pages in unavailable ranges machine # [ 0.022981] On node 0, zone DMA32: 40 pages in unavailable ranges machine # [ 0.023446] ACPI: PM-Timer IO Port: 0x608 machine # [ 0.023457] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) machine # [ 0.023485] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 machine # [ 0.023488] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) machine # [ 0.023489] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) machine # [ 0.023490] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) machine # [ 0.023492] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) machine # [ 0.023492] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) machine # [ 0.023495] ACPI: Using ACPI (MADT) for SMP configuration information machine # [ 0.023495] ACPI: HPET id: 0x8086a201 base: 0xfed00000 machine # [ 0.023499] TSC deadline timer available machine # [ 0.023503] CPU topo: Max. logical packages: 1 machine # [ 0.023504] CPU topo: Max. logical dies: 1 machine # [ 0.023504] CPU topo: Max. dies per package: 1 machine # [ 0.023508] CPU topo: Max. threads per core: 1 machine # [ 0.023508] CPU topo: Num. cores per package: 1 machine # [ 0.023509] CPU topo: Num. threads per package: 1 machine # [ 0.023509] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs machine # [ 0.023534] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() machine # [ 0.023569] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] machine # [ 0.023571] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] machine # [ 0.023572] [mem 0x40000000-0xafffffff] available for PCI devices machine # [ 0.023574] Booting paravirtualized kernel on KVM machine # [ 0.023576] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns machine # [ 0.028032] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 machine # [ 0.030166] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152 machine # [ 0.030210] kvm-guest: PV spinlocks disabled, single CPU machine # [ 0.030212] 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/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/init regInfo=/nix/store/db5xmkvnyxhf3lh2h7r4i09pmwydi0pn-closure-info/registration console=ttyS0,115200n8 console=tty0 machine # [ 0.030307] Unknown kernel command line parameters "regInfo=/nix/store/db5xmkvnyxhf3lh2h7r4i09pmwydi0pn-closure-info/registration", will be passed to user space. machine # [ 0.030321] random: crng init done machine # [ 0.030322] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes machine # [ 0.030346] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.030358] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.030389] Fallback order for Node 0: 0 machine # [ 0.030392] Built 1 zonelists, mobility grouping on. Total pages: 262006 machine # [ 0.030393] Policy zone: DMA32 machine # [ 0.032797] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.035222] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 machine # [ 0.037600] allocated 2097152 bytes of page_ext machine # [ 0.047414] ftrace: allocating 48736 entries in 192 pages machine # [ 0.047416] ftrace: allocated 192 pages with 2 groups machine # [ 0.048236] Dynamic Preempt: lazy machine # [ 0.048385] rcu: Preemptible hierarchical RCU implementation. machine # [ 0.048386] rcu: RCU event tracing is enabled. machine # [ 0.048386] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. machine # [ 0.048388] Trampoline variant of Tasks RCU enabled. machine # [ 0.048389] Rude variant of Tasks RCU enabled. machine # [ 0.048389] Tracing variant of Tasks RCU enabled. machine # [ 0.048390] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. machine # [ 0.048390] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 machine # [ 0.048438] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.048440] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.048441] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.052782] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16 machine # [ 0.053065] rcu: srcu_init: Setting srcu_struct sizes based on contention. machine # [ 0.053072] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns machine # [ 0.053178] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.056745] Console: colour VGA+ 80x25 machine # [ 0.056748] printk: legacy console [tty0] enabled machine # [ 0.085897] printk: legacy console [ttyS0] enabled machine # [ 0.192355] ACPI: Core revision 20250807 machine # [ 0.193299] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns machine # [ 0.194937] APIC: Switch to symmetric I/O mode setup machine # [ 0.195940] x2apic enabled machine # [ 0.196774] APIC: Switched APIC routing to: physical x2apic machine # [ 0.198673] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 machine # [ 0.199747] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns machine # [ 0.201449] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012) machine # [ 0.203467] x86/cpu: User Mode Instruction Prevention (UMIP) activated machine # [ 0.204592] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 machine # [ 0.205446] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 machine # [ 0.206450] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto machine # [ 0.207446] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl machine # [ 0.208446] Transient Scheduler Attacks: Vulnerable: No microcode machine # [ 0.209446] Spectre V2 : Mitigation: Enhanced / Automatic IBRS machine # [ 0.210445] Speculative Return Stack Overflow: Mitigation: Safe RET machine # [ 0.211445] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization machine # [ 0.213453] Spectre V2 : Enabling IBPB for BPF machine # [ 0.214446] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier machine # [ 0.215446] active return thunk: srso_alias_return_thunk machine # [ 0.217162] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' machine # [ 0.218446] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' machine # [ 0.219446] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' machine # [ 0.220445] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' machine # [ 0.221445] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' machine # [ 0.222445] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' machine # [ 0.223445] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' machine # [ 0.224446] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 machine # [ 0.225445] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 machine # [ 0.226445] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 machine # [ 0.227446] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 machine # [ 0.229218] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 machine # [ 0.230244] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format. machine # [ 0.266933] Freeing SMP alternatives memory: 44K machine # [ 0.267449] pid_max: default: 32768 minimum: 301 machine # [ 0.268547] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.269570] landlock: Up and running. machine # [ 0.270447] Yama: becoming mindful. machine # [ 0.271492] LSM support for eBPF active machine # [ 0.272262] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.273476] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.276022] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1) machine # [ 0.277009] Performance Events: Fam17h+ core perfctr, AMD PMU driver. machine # [ 0.277450] ... version: 2 machine # [ 0.278184] ... bit width: 48 machine # [ 0.278448] ... generic counters: 6 machine # [ 0.279170] ... generic bitmap: 000000000000003f machine # [ 0.279453] ... fixed-purpose counters: 0 machine # [ 0.280186] ... fixed-purpose bitmap: 0000000000000000 machine # [ 0.280447] ... value mask: 0000ffffffffffff machine # [ 0.281385] ... max period: 00007fffffffffff machine # [ 0.282175] ... global_ctrl mask: 000000000000003f machine # [ 0.282556] signal: max sigframe size: 3376 machine # [ 0.283407] rcu: Hierarchical SRCU implementation. machine # [ 0.284096] rcu: Max phase no-delay instances is 400. machine # [ 0.289071] smp: Bringing up secondary CPUs ... machine # [ 0.289470] smp: Brought up 1 node, 1 CPU machine # [ 0.290178] smpboot: Total of 1 processors activated (4800.02 BogoMIPS) machine # [ 0.290632] Memory: 941740K/1048024K available (17231K kernel code, 2726K rwdata, 13600K rodata, 3644K init, 2988K bss, 98892K reserved, 0K cma-reserved) machine # [ 0.291689] devtmpfs: initialized machine # [ 0.292497] x86/mm: Memory block size: 128MB machine # [ 0.294167] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.295427] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.296535] pinctrl core: initialized pinctrl subsystem machine # [ 0.297699] PM: RTC time: 03:04:00, date: 2026-09-14 machine # [ 0.301154] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.301810] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.302464] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.303591] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.304457] audit: initializing netlink subsys (disabled) machine # [ 0.305661] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.305663] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.306449] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.307451] audit: type=2000 audit(1789355040.668:1): state=initialized audit_enabled=0 res=1 machine # [ 0.309450] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.309452] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.310465] cpuidle: using governor menu machine # [ 0.312736] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 machine # [ 0.314043] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] machine # [ 0.314451] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry machine # [ 0.315460] PCI: Using configuration type 1 for base access machine # [ 0.316628] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. machine # [ 0.321726] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.322448] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.327449] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.328448] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.338886] ACPI: Added _OSI(Module Device) machine # [ 0.339450] ACPI: Added _OSI(Processor Device) machine # [ 0.340255] ACPI: Added _OSI(Processor Aggregator Device) machine # [ 0.350119] ACPI: 1 ACPI AML tables successfully acquired and loaded machine # [ 0.353878] ACPI: Interpreter enabled machine # [ 0.354463] ACPI: PM: (supports S0 S3 S4 S5) machine # [ 0.355208] ACPI: Using IOAPIC for interrupt routing machine # [ 0.357527] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug machine # [ 0.358449] PCI: Using E820 reservations for host bridge windows machine # [ 0.361592] ACPI: Enabled 2 GPEs in block 00 to 3F machine # [ 0.369143] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) machine # [ 0.369455] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] machine # [ 0.370527] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] machine # [ 0.371569] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] machine # [ 0.372791] PCI host bridge to bus 0000:00 machine # [ 0.373452] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] machine # [ 0.374448] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] machine # [ 0.375448] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] machine # [ 0.376448] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window] machine # [ 0.377448] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] machine # [ 0.378448] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] machine # [ 0.379448] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.380454] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.381884] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint machine # [ 0.384519] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] machine # [ 0.385460] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] machine # [ 0.386453] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] machine # [ 0.387781] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] machine # [ 0.389152] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.391512] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f] machine # [ 0.392421] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] machine # [ 0.393216] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] machine # [ 0.393454] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] machine # [ 0.395009] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.396464] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f] machine # [ 0.397352] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] machine # [ 0.398244] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] machine # [ 0.399855] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.402464] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f] machine # [ 0.403454] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] machine # [ 0.404447] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] machine # [ 0.406005] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.407455] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f] machine # [ 0.408359] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] machine # [ 0.409267] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref] machine # [ 0.410357] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref] machine # [ 0.411987] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.413312] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] machine # [ 0.414256] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref] machine # [ 0.415942] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.417489] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] machine # [ 0.418469] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref] machine # [ 0.420007] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.421298] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] machine # [ 0.422255] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref] machine # [ 0.423890] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.425461] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] machine # [ 0.426469] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref] machine # [ 0.428010] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.429494] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf] machine # [ 0.430405] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] machine # [ 0.431256] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref] machine # [ 0.432926] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.435454] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f] machine # [ 0.436348] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff] machine # [ 0.437237] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref] machine # [ 0.438937] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.440023] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f] machine # [ 0.440647] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.441992] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf] machine # [ 0.442644] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.443993] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df] machine # [ 0.444712] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.446994] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff] machine # [ 0.447698] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint machine # [ 0.448741] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO machine # [ 0.449694] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint machine # [ 0.451360] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff] machine # [ 0.452129] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff] machine # [ 0.452778] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint machine # [ 0.454025] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] machine # [ 0.457379] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 machine # [ 0.458555] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 machine # [ 0.459552] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 machine # [ 0.460545] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 machine # [ 0.461543] ACPI: PCI: Interrupt link LNKE configured for IRQ 10 machine # [ 0.462542] ACPI: PCI: Interrupt link LNKF configured for IRQ 10 machine # [ 0.463555] ACPI: PCI: Interrupt link LNKG configured for IRQ 11 machine # [ 0.464543] ACPI: PCI: Interrupt link LNKH configured for IRQ 11 machine # [ 0.465484] ACPI: PCI: Interrupt link GSIA configured for IRQ 16 machine # [ 0.466462] ACPI: PCI: Interrupt link GSIB configured for IRQ 17 machine # [ 0.467462] ACPI: PCI: Interrupt link GSIC configured for IRQ 18 machine # [ 0.468463] ACPI: PCI: Interrupt link GSID configured for IRQ 19 machine # [ 0.469460] ACPI: PCI: Interrupt link GSIE configured for IRQ 20 machine # [ 0.470459] ACPI: PCI: Interrupt link GSIF configured for IRQ 21 machine # [ 0.471459] ACPI: PCI: Interrupt link GSIG configured for IRQ 22 machine # [ 0.472459] ACPI: PCI: Interrupt link GSIH configured for IRQ 23 machine # [ 0.474544] iommu: Default domain type: Translated machine # [ 0.475388] iommu: DMA domain TLB invalidation policy: lazy mode machine # [ 0.476511] ACPI: bus type USB registered machine # [ 0.477295] usbcore: registered new interface driver usbfs machine # [ 0.478164] usbcore: registered new interface driver hub machine # [ 0.478456] usbcore: registered new device driver usb machine # [ 0.480194] NetLabel: Initializing machine # [ 0.480454] NetLabel: domain hash size = 128 machine # [ 0.481228] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO machine # [ 0.481480] NetLabel: unlabeled traffic allowed by default machine # [ 0.482434] PCI: Using ACPI for IRQ routing machine # [ 0.525683] pci 0000:00:01.0: vgaarb: setting as boot VGA device machine # [ 0.526444] pci 0000:00:01.0: vgaarb: bridge control possible machine # [ 0.526444] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none machine # [ 0.526452] vgaarb: loaded machine # [ 0.527209] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 machine # [ 0.527450] hpet0: 3 comparators, 64-bit 100.000000 MHz counter machine # [ 0.531528] clocksource: Switched to clocksource kvm-clock machine # [ 0.533232] VFS: Disk quotas dquot_6.6.0 machine # [ 0.535736] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.537099] pnp: PnP ACPI init machine # [ 0.537941] ACPI: IRQ 4 override to edge(!), high(!) machine # [ 0.538930] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved machine # [ 0.540500] pnp: PnP ACPI: found 5 devices machine # [ 0.548115] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns machine # [ 0.549657] clocksource: Switched to clocksource acpi_pm machine # [ 0.550650] NET: Registered PF_INET protocol family machine # [ 0.551681] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.568236] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.569776] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.571106] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.572442] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.573713] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.574832] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.576173] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.577310] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.578568] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.579537] NET: Registered PF_XDP protocol family machine # [ 0.580396] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] machine # [ 0.581395] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] machine # [ 0.582419] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] machine # [ 0.583537] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window] machine # [ 0.584693] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] machine # [ 0.585820] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] machine # [ 0.587576] ACPI: \_SB_.GSIA: Enabled at IRQ 16 machine # [ 0.589767] ACPI: \_SB_.GSIB: Enabled at IRQ 17 machine # [ 0.592105] ACPI: \_SB_.GSIC: Enabled at IRQ 18 machine # [ 0.594041] ACPI: \_SB_.GSID: Enabled at IRQ 19 machine # [ 0.595772] PCI: CLS 0 bytes, default 64 machine # [ 0.596687] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns machine # [ 0.598419] Trying to unpack rootfs image as initramfs... machine # [ 0.642555] Initialise system trusted keyrings machine # [ 0.645452] workingset: timestamp_bits=40 max_order=18 bucket_order=0 machine # [ 0.668458] Key type asymmetric registered machine # [ 0.669146] Asymmetric key parser 'x509' registered machine # [ 0.670023] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) machine # [ 0.673535] io scheduler mq-deadline registered machine # [ 0.674308] io scheduler kyber registered machine # [ 0.677056] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.680406] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A machine # [ 0.684019] Linux agpgart interface v0.103 machine # [ 0.684844] ACPI: bus type drm_connector registered machine # [ 0.687850] usbcore: registered new interface driver usbserial_generic machine # [ 0.688976] usbserial: USB Serial support registered for generic machine # [ 0.691657] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS. machine # [ 0.691657] Please enable it if your BIOS has the CPPC option. machine # [ 0.693983] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled machine # [ 0.699525] drop_monitor: Initializing network drop monitor service machine # [ 0.700718] NET: Registered PF_INET6 protocol family machine # [ 0.703841] Segment Routing with IPv6 machine # [ 0.706418] In-situ OAM (IOAM) with IPv6 machine # [ 0.707508] IPI shorthand broadcast: enabled machine # [ 0.715928] sched_clock: Marking stable (572014062, 143372399)->(785784951, -70398490) machine # [ 0.720696] registered taskstats version 1 machine # [ 0.721627] Loading compiled-in X.509 certificates machine # [ 0.739415] Demotion targets for Node 0: null machine # [ 0.742441] Key type .fscrypt registered machine # [ 0.743072] Key type fscrypt-provisioning registered machine # [ 0.744059] ima: No TPM chip found, activating TPM-bypass! machine # [ 0.746403] ima: Allocated hash algorithm: sha1 machine # [ 0.747192] ima: No architecture policies found machine # [ 0.749404] PM: Magic number: 10:897:57 machine # [ 0.751034] RAS: Correctable Errors collector initialized. machine # [ 0.762063] clk: Disabling unused clocks machine # [ 0.764399] PM: genpd: Disabling unused power domains machine # [ 0.915310] Freeing initrd memory: 28368K machine # [ 0.918509] Freeing unused decrypted memory: 2028K machine # [ 0.921125] Freeing unused kernel image (initmem) memory: 3644K machine # [ 0.922242] Write protecting the kernel read-only data: 32768k machine # [ 0.924197] Freeing unused kernel image (text/rodata gap) memory: 1200K machine # [ 0.925757] Freeing unused kernel image (rodata/data gap) memory: 736K machine # [ 0.978063] x86/mm: Checked W+X mappings: passed, no W+X pages found. machine # [ 0.979180] Run /init as init process machine # [ 0.990046] systemd[1]: Inserted module 'autofs4' machine # [ 1.010467] fuse: init (API version 7.45) machine # [ 1.017926] ACPI: \_SB_.GSIG: Enabled at IRQ 22 machine # [ 1.020280] ACPI: \_SB_.GSIH: Enabled at IRQ 23 machine # [ 1.023473] ACPI: \_SB_.GSIE: Enabled at IRQ 20 machine # [ 1.025403] ACPI: \_SB_.GSIF: Enabled at IRQ 21 machine # [ 1.030394] virtiofs virtio5: discovered new tag: nix-store machine # [ 1.031976] virtiofs virtio5: virtio_fs_setup_dax: No cache capability machine # [ 1.038734] virtiofs virtio6: discovered new tag: shared machine # [ 1.040351] virtiofs virtio6: virtio_fs_setup_dax: No cache capability machine # [ 1.043424] virtiofs virtio7: discovered new tag: xchg machine # [ 1.044911] virtiofs virtio7: virtio_fs_setup_dax: No cache capability machine # [ 1.064105] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.400793] systemd[1]: systemd 262-5698092 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.412437] systemd[1]: Detected virtualization kvm. machine # [ 1.414487] systemd[1]: Detected architecture x86-64. machine # [ 1.416621] systemd[1]: Running in initrd. machine # [ 1.418996] systemd[1]: Initializing machine ID from random generator. machine # [ 1.421858] systemd[1]: Hostname set to . machine # [ 1.513212] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.548847] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.552656] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.553864] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.555302] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.556456] systemd[1]: Reached target Path Units. machine # [ 1.557321] systemd[1]: Reached target Slice Units. machine # [ 1.558233] systemd[1]: Reached target Swaps. machine # [ 1.559055] systemd[1]: Reached target Timer Units. machine # [ 1.560053] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.561334] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.562549] systemd[1]: Listening on Journal Sockets. machine # [ 1.563519] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.564469] systemd[1]: Reached target Socket Units. machine # [ 1.566162] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.570171] systemd[1]: Starting Load Kernel Module configfs... machine # [ 1.577004] systemd[1]: Starting Journal Service... machine # [ 1.588251] systemd[1]: Starting Load Kernel Modules... machine # [ 1.604258] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.611611] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.625734] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.632048] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 1.639012] systemd-journald[66]: Collecting audit messages is disabled. machine # [ 1.640629] systemd[1]: Finished Load Kernel Module configfs. machine # [ 1.644254] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config machine # [ 1.655170] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.659900] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.670539] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.695924] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.704574] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.719451] systemd[1]: Finished Load Kernel Modules. machine # [ 1.725578] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.740414] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.746648] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.751467] systemd[1]: Reached target Local File Systems. machine # [ 1.759597] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.619534] systemd-modules-load[67]: Inserted module 'dm_mod' machine # [ 1.620771] systemd-modules-load[67]: Inserted module 'virtio_balloon' machine # [ 1.621827] systemd-modules-load[67]: Inserted module 'virtio_gpu' machine # [ 1.770682] systemd[1]: Started Journal Service. machine # [ 1.634304] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.648510] systemd[1]: Starting Create System Files and Directories... machine # [ 1.675438] systemd[1]: Finished Create System Files and Directories. machine # [ 1.679389] systemd-udevd[75]: Using default interface naming scheme 'v261'. machine # [ 1.703909] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 1.763093] systemd[1]: Finished Coldplug All udev Devices. machine # [ 1.765515] systemd[1]: Reached target System Initialization. machine # [ 1.766383] systemd[1]: Reached target Basic System. machine # [ 2.084233] virtio_blk virtio2: 1/0/0 default/read/poll queues machine # [ 2.088185] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 machine # [ 2.099022] serio: i8042 KBD port at 0x60,0x64 irq 1 machine # [ 2.104414] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 2.106933] serio: i8042 AUX port at 0x60,0x64 irq 12 machine # [ 2.111963] ehci-pci 0000:00:1d.7: EHCI Host Controller machine # [ 2.112737] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1 machine # [ 2.114456] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000 machine # [ 2.120416] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 machine # [ 2.123446] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18 machine # [ 2.124524] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.127400] usb usb1: Product: EHCI Host Controller machine # [ 2.128077] usb usb1: Manufacturer: Linux 6.18.51 ehci_hcd machine # [ 2.130197] usb usb1: SerialNumber: 0000:00:1d.7 machine # [ 2.131794] hub 1-0:1.0: USB hub found machine # [ 2.134457] hub 1-0:1.0: 6 ports detected machine # [ 2.135921] uhci_hcd 0000:00:1d.0: UHCI Host Controller machine # [ 2.136672] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2 machine # [ 2.147744] uhci_hcd 0000:00:1d.0: detected 2 ports machine # [ 2.156222] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c180 machine # [ 2.159627] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 2.160767] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.174803] usb usb2: Product: UHCI Host Controller machine # [ 2.179628] usb usb2: Manufacturer: Linux 6.18.51 uhci_hcd machine # [ 2.189082] usb usb2: SerialNumber: 0000:00:1d.0 machine # [ 2.190709] SCSI subsystem initialized machine # [ 2.197316] hub 2-0:1.0: USB hub found machine # [ 2.199862] hub 2-0:1.0: 2 ports detected machine # [ 2.059427] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.063261] systemd[1]: Starting Virtual Console Setup... machine # [ 2.069563] (udev-worker)[91]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.220813] uhci_hcd 0000:00:1d.1: UHCI Host Controller machine # [ 2.079299] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.229796] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 machine # [ 2.249251] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3 machine # [ 2.250281] uhci_hcd 0000:00:1d.1: detected 2 ports machine # [ 2.117276] systemd-vconsole-setup[98]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.120607] systemd[1]: Finished Virtual Console Setup. machine # [ 2.270621] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a0 machine # [ 2.277802] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 2.136668] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 2.138466] systemd[1]: Reached target Initrd Root Device. machine # [ 2.283881] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.143152] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 2.291118] usb usb3: Product: UHCI Host Controller machine # [ 2.291956] usb usb3: Manufacturer: Linux 6.18.51 uhci_hcd machine # [ 2.297425] usb usb3: SerialNumber: 0000:00:1d.1 machine # [ 2.299532] hub 3-0:1.0: USB hub found machine # [ 2.305407] hub 3-0:1.0: 2 ports detected machine # [ 2.309437] uhci_hcd 0000:00:1d.2: UHCI Host Controller machine # [ 2.310180] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4 machine # [ 2.313502] uhci_hcd 0000:00:1d.2: detected 2 ports machine # [ 2.316475] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c0 machine # [ 2.318514] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 2.321510] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.184491] systemd-fsck[106]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 2.331784] usb usb4: Product: UHCI Host Controller machine # [ 2.332569] usb usb4: Manufacturer: Linux 6.18.51 uhci_hcd machine # [ 2.335506] usb usb4: SerialNumber: 0000:00:1d.2 machine # [ 2.336750] hub 4-0:1.0: USB hub found machine # [ 2.339395] hub 4-0:1.0: 2 ports detected machine # [ 2.353644] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode machine # [ 2.359408] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) machine # [ 2.360213] ahci 0000:00:1f.2: flags: 64bit ncq only machine # [ 2.367470] scsi host0: ahci machine # [ 2.368721] scsi host1: ahci machine # [ 2.370424] scsi host2: ahci machine # [ 2.372670] scsi host3: ahci machine # [ 2.374336] scsi host4: ahci machine # [ 2.374850] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 2.377157] scsi host5: ahci machine # [ 2.378337] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 1 machine # [ 2.379976] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 1 machine # [ 2.381141] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 1 machine # [ 2.382299] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 1 machine # [ 2.383548] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 1 machine # [ 2.384782] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 1 machine # [ 2.503816] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 machine # [ 2.506739] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 machine # [ 2.509958] usb 1-1: Product: QEMU USB Tablet machine # [ 2.511788] usb 1-1: Manufacturer: QEMU machine # [ 2.513551] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1 machine # [ 2.550280] hid: raw HID events driver (C) Jiri Kosina machine # [ 2.693018] ata2: SATA link down (SStatus 0 SControl 300) machine # [ 2.695275] ata1: SATA link down (SStatus 0 SControl 300) machine # [ 2.697833] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) machine # [ 2.700301] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 machine # [ 2.702348] ata3.00: applying bridge limits machine # [ 2.704468] ata4: SATA link down (SStatus 0 SControl 300) machine # [ 2.706746] ata6: SATA link down (SStatus 0 SControl 300) machine # [ 2.708787] ata5: SATA link down (SStatus 0 SControl 300) machine # [ 2.711328] ata3.00: configured for UDMA/100 machine # [ 2.713683] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 machine # [ 2.764519] usbcore: registered new interface driver usbhid machine # [ 2.765270] usbhid: USB HID core driver machine # [ 2.771091] 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 # [ 2.772806] 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 # [ 2.778550] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray machine # [ 2.788490] cdrom: Uniform CD-ROM driver Revision: 3.20 machine # [ 2.694605] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 2.699241] systemd[1]: Mounting /sysroot... machine # [ 3.046119] EXT4-fs (vda): mounted filesystem d3eae1cc-a688-4195-be7e-c696f63c47e7 r/w with ordered data mode. Quota mode: none. machine # [ 2.910911] systemd[1]: Mounted /sysroot. machine # [ 2.914162] systemd[1]: Reached target Initrd Root File System. machine # [ 2.919726] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 2.925940] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 2.933880] systemd[1]: Mounting /sysroot/run... machine # [ 2.940275] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 2.951447] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 2.954152] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 2.984043] systemd-sysroot-fstab-check[145]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 2.992844] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 2.998062] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.001403] systemd[1]: Mounted /sysroot/run. machine # [ 3.002729] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.003742] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.010111] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.011389] systemd[1]: Reload requested from client PID 145 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 3.012865] systemd[1]: Reloading... machine # [ 3.075310] systemd[1]: Reloading finished in 61 ms. machine # [ 3.084416] systemd-sysroot-fstab-check[145]: Requesting initrd-fs.target/start/replace... machine # [ 3.086485] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.088072] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.089960] systemd-sysroot-fstab-check[145]: Requesting swap.target/start/replace... machine # [ 3.093285] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.095522] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 3.096872] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 3.097888] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 3.108611] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.110088] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.481841] systemd[1]: Mounting /sysroot/nix/store... machine # [ 3.531485] systemd[1]: Mounted /sysroot/nix/store. machine # [ 3.533915] systemd[1]: Reached target Initrd File Systems. machine # [ 3.538137] systemd[1]: Starting Find NixOS closure... machine # [ 3.541928] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 3.566810] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 3.568536] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. machine # [ 3.576297] systemd[1]: Finished Find NixOS closure. machine # [ 3.578165] systemd[1]: Reached target Initrd Default Target. machine # [ 3.579534] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 3.593274] systemd[1]: Stopped target Initrd Default Target. machine # [ 3.594507] systemd[1]: Stopped target Basic System. machine # [ 3.595455] systemd[1]: Stopped target Initrd Root Device. machine # [ 3.597113] systemd[1]: Stopped target Path Units. machine # [ 3.597806] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 3.598919] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 3.600020] systemd[1]: Stopped target Slice Units. machine # [ 3.600719] systemd[1]: Stopped target Socket Units. machine # [ 3.601683] systemd[1]: Stopped target System Initialization. machine # [ 3.602743] systemd[1]: Stopped target Swaps. machine # [ 3.603740] systemd[1]: Stopped target Timer Units. machine # [ 3.605134] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 3.605938] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 3.606779] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 3.607961] systemd[1]: Stopped Find NixOS closure. machine # [ 3.609292] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.611113] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 3.612167] systemd[1]: Stopped Apply Kernel Variables. machine # [ 3.613105] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 3.614320] systemd[1]: Stopped Load Kernel Modules. machine # [ 3.615241] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 3.616897] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 3.617970] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 3.619570] systemd[1]: Stopped Create System Files and Directories. machine # [ 3.620436] systemd[1]: Stopped target Local File Systems. machine # [ 3.622408] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 3.623318] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 3.624249] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 3.626087] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 3.628144] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 3.629101] systemd[1]: Stopped Virtual Console Setup. machine # [ 3.635441] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 3.638604] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 3.641568] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 3.643248] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 3.649158] systemd[1]: Starting Cleanup udev Database... machine # [ 3.649970] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 3.651175] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 3.653154] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 3.654277] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 3.656081] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 3.657086] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 3.657939] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.658995] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.673976] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 3.676198] systemd[1]: Finished Cleanup udev Database. machine # [ 3.676908] systemd[1]: Reached target Switch Root. machine # [ 3.678455] systemd[1]: Starting NixOS Activation... machine # [ 3.740918] initrd-nixos-activation-start[190]: booting system configuration /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test machine # [ 3.766513] initrd-nixos-activation-start[190]: running activation script... machine # [ 3.954792] initrd-nixos-activation-start[213]: setting up /etc... machine # [ 4.060160] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 4.062127] systemd[1]: Finished NixOS Activation. machine # [ 4.063641] systemd[1]: Starting Switch Root... machine # [ 4.077176] systemd[1]: Switching root. machine # [ 4.347586] systemd-journald[66]: Received SIGTERM from PID 1 (systemd). machine # [ 4.425474] NET: Registered PF_VSOCK protocol family machine # [ 4.784076] systemd[1]: systemd 262-5698092 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 # [ 4.793703] systemd[1]: Detected virtualization kvm. machine # [ 4.795440] systemd[1]: Detected architecture x86-64. machine # [ 4.797283] systemd[1]: Detected first boot. machine # [ 4.801162] systemd[1]: Initializing machine ID from random generator. machine # [ 5.063288] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 5.154764] systemd[1]: Applying preset policy. machine # [ 5.319958] systemd[1]: Populated /etc with preset unit settings. machine # [ 5.492977] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 5.494285] systemd[1]: Stopped initrd-switch-root.service. machine # [ 5.496721] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 5.498814] systemd[1]: Created slice Slice /system/getty. machine # [ 5.500111] systemd[1]: Created slice User and Session Slice. machine # [ 5.501038] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 5.502252] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 5.503315] systemd[1]: Expecting device /dev/hvc0... machine # [ 5.504047] systemd[1]: Expecting device /dev/ttyS0... machine # [ 5.504826] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 5.505661] systemd[1]: Stopped target initrd-fs.target. machine # [ 5.506395] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 5.507145] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 5.507986] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 5.508895] systemd[1]: Reached target Path Units. machine # [ 5.509613] systemd[1]: Reached target Remote File Systems. machine # [ 5.510356] systemd[1]: Reached target Slice Units. machine # [ 5.511067] systemd[1]: Reached target Swaps. machine # [ 5.512776] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 5.526268] systemd[1]: Listening on Process Core Dump Socket. machine # [ 5.527871] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 5.529562] systemd[1]: Listening on Factory Reset Management. machine # [ 5.530455] systemd[1]: Listening on Hostname Service Socket. machine # [ 5.532911] systemd[1]: Starting Journal Log Access Socket... machine # [ 5.534253] systemd[1]: Listening on Journal Audit Socket. machine # [ 5.536623] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 5.537766] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 5.539271] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 5.540537] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 5.544635] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 5.545652] systemd[1]: Listening on udev Varlink Socket. machine # [ 5.547883] systemd[1]: Mounting Huge Pages File System... machine # [ 5.550061] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 5.556937] systemd[1]: Mounting Kernel Debug File System... machine # [ 5.564064] systemd[1]: Mounting Kernel Trace File System... machine # [ 5.571010] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 5.582849] systemd[1]: Starting Load Kernel Module configfs... machine # [ 5.591705] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 5.598736] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 5.608619] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 5.623518] systemd[1]: Mounting FUSE Control File System... machine # [ 5.624351] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 5.636875] systemd[1]: Starting Journal Service... machine # [ 5.644904] systemd[1]: Starting Load Kernel Modules... machine # [ 5.657985] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 5.670544] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 5.672482] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 5.681506] systemd[1]: Starting Coldplug All udev Devices... machine # [ 5.694153] systemd[1]: Listening on Journal Log Access Socket. machine # [ 5.695917] systemd-journald[284]: Collecting audit messages is enabled. machine # [ 5.699643] systemd[1]: Mounted Huge Pages File System. machine # [ 5.703718] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 5.715554] systemd[1]: Mounted Kernel Debug File System. machine # [ 5.722539] systemd[1]: Mounted Kernel Trace File System. machine # [ 5.586251] systemd[1]: Queued start job for default target Multi-User System. machine # [ 5.587537] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 5.732306] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 5.735561] loop: module loaded machine # [ 5.738235] systemd[1]: Started Journal Service. machine # [ 5.595969] systemd-modules-load[285]: Inserted module 'loop' machine # [ 5.599211] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 5.602543] systemd[1]: Finished Load Kernel Module configfs. machine # [ 5.606630] systemd[1]: Mounted FUSE Control File System. machine # [ 5.612089] systemd[1]: Finished Load Kernel Modules. machine # [ 5.769432] EXT4-fs (vda): re-mounted d3eae1cc-a688-4195-be7e-c696f63c47e7. machine # [ 5.633082] systemd[1]: Mounting Kernel Configuration File System... machine # [ 5.637051] systemd[1]: Starting Firewall... machine # [ 5.648367] systemd[1]: Starting Apply Kernel Variables... machine # [ 5.664260] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 5.669245] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 5.673205] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 5.680323] systemd-oomd[287]: No swap; memory pressure usage will be degraded machine # [ 5.689075] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 5.689951] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 5.705125] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 5.705906] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 5.708853] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 5.768785] systemd[1]: Finished Apply Kernel Variables. machine # [ 5.918232] systemd-journald[284]: Received client request to flush runtime journal. machine # [ 5.856679] systemd[1]: Mounted Kernel Configuration File System. machine # [ 5.858721] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 5.860334] systemd[1]: Reached target First Boot Complete. machine # [ 5.866168] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 5.919107] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 5.923446] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 5.950134] systemd[1]: Finished Coldplug All udev Devices. machine # [ 5.973129] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 5.974187] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 5.977336] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 6.020971] systemd-udevd[328]: Using default interface naming scheme 'v261'. machine # [ 6.070283] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 6.183092] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 6.271705] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 6.286241] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped. machine # [ 6.316748] (udev-worker)[356]: Network interface NamePolicy= disabled on kernel command line. machine # [ 6.322565] (udev-worker)[354]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 6.324947] (udev-worker)[354]: Network interface NamePolicy= disabled on kernel command line. machine # [ 6.353485] systemd[1]: Mounting /run/wrappers... machine # [ 6.390965] systemd[1]: Mounted /run/wrappers. machine # [ 6.393064] systemd[1]: Reached target Local File Systems. machine # [ 6.395149] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 6.399524] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 6.401149] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 6.405592] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 6.414907] systemd[1]: Starting Create System Files and Directories... machine # [ 6.487306] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 6.494547] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 6.536687] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 6.540481] systemd[1]: Finished Create System Files and Directories. machine # [ 6.542475] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 6.544055] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 6.546498] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 6.552076] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 6.552895] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 6.555130] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.557283] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.563770] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 6.636561] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 6.666402] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 6.672192] systemd[1]: Starting Update is Completed... machine # [ 6.713400] systemd[1]: Finished Update is Completed. machine # [ 6.897401] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console machine # [ 6.861489] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 6.865685] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 6.866519] systemd[1]: Reached target System Initialization. machine # [ 6.868340] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 6.869377] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 6.871216] systemd[1]: Reached target Timer Units. machine # [ 6.871890] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 6.873253] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 6.875285] systemd[1]: Reached target Socket Units. machine # [ 6.875980] systemd[1]: Reached target Basic System. machine # [ 6.880641] systemd[1]: Started backdoor.service. machine # [ 6.884205] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 6.891200] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 6.910241] systemd[1]: Starting Post-Boot Actions... machine # [ 6.918665] systemd[1]: Started Reset console on configuration changes. machine # [ 6.929807] systemd[1]: Starting resolvconf update... machine # [ 6.944344] systemd[1]: Finished Firewall. machine # [ 6.977618] systemd[1]: Starting D-Bus System Message Bus... machine # [ 7.004868] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 7.008144] nsncd[493]: Sep 14 03:04:07.573 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 6.905162] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 machine # [ 7.015481] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 7.019380] systemd[1]: Reached target User and Group Name Lookups. machine # connecting to host... machine # [ 7.038936] systemd[1]: Starting User Login Management... machine # [ 6.916105] ACPI: button: Power Button [PWRF] machine # [ 6.918977] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input4 machine # [ 7.045573] systemd[1]: Finished Post-Boot Actions. machine: Guest shell says: b'Spawning backdoor root shell...\n' machine: connected to guest root shell machine: (connecting took 7.73 seconds) machine: (finished: waiting for the VM to finish booting, in 8.02 seconds) machine # [ 6.937857] mousedev: PS/2 mouse device common for all mice machine # [ 7.042046] rtc_cmos PNP0B00:00: RTC can wake from S4 machine # [ 7.110900] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 7.076567] rtc_cmos PNP0B00:00: registered as rtc0 machine # [ 7.076629] rtc_cmos PNP0B00:00: setting system clock to 2026-09-14T03:04:07 UTC (1789355047) machine # [ 7.076703] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs machine # [ 7.138336] dbus-broker-launch[499]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 7.144919] dbus-broker-launch[499]: NSS returned no entry for 'systemd-timesync' machine # [ 7.146132] dbus-broker-launch[499]: Invalid user-name in /nix/store/r9spxqyp1c7673bc4ad24fnmdicn6lhw-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 7.156729] Console: switching to colour dummy device 80x25 machine # [ 7.166717] parport_pc 00:02: reported by Plug and Play ACPI machine # [ 7.166806] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] machine # [ 7.205257] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized machine # [ 7.363643] [drm] Found bochs VGA, ID 0xb0c5. machine # [ 7.363645] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. machine # [ 7.223050] systemd-logind[516]: New seat seat0. machine # [ 7.230545] dbus-broker-launch[499]: Ready machine # [ 7.379773] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic machine # [ 7.380435] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0 machine # [ 7.386040] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt machine # [ 7.386741] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD machine # [ 7.291495] systemd[1]: Started D-Bus System Message Bus. machine # [ 7.293808] systemd[1]: Started User Login Management. machine # [ 7.307685] systemd[1]: Starting linger-users.service... machine # [ 7.314219] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 7.316137] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 7.320123] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 7.321122] systemd[1]: Stopping User and Group Name Lookups... machine # [ 7.323265] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 7.331074] systemd[1]: nscd.service: Deactivated successfully. machine # [ 7.334077] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 7.358666] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 7.391559] systemd[1]: Starting Virtual Console Setup... machine # [ 7.432193] nsncd[581]: Sep 14 03:04:07.998 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 7.436904] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 7.438835] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6 machine # [ 7.439809] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5 machine # [ 7.525261] Console: switching to colour frame buffer device 160x50 machine # [ 7.638182] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device machine # [ 7.472304] systemd[1]: Created slice Slice /user/1001. machine # [ 7.520124] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 7.530897] systemd[1]: Reached target User and Group Name Lookups. machine # [ 7.531792] systemd[1]: Starting User Runtime Directory /run/user/1001... machine # [ 7.532689] systemd-logind[516]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) machine # [ 7.536182] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 7.536990] systemd[1]: Finished linger-users.service. machine # [ 7.547963] systemd-logind[516]: Watching system buttons on /dev/input/event2 (Power Button) machine # [ 7.549105] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 7.550057] systemd[1]: Stopped Virtual Console Setup. machine # [ 7.550763] systemd[1]: Starting Virtual Console Setup... machine # [ 7.561730] systemd-logind[516]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 7.732137] ppdev: user-space parallel port driver machine # [ 7.589723] systemd[1]: Finished resolvconf update. machine # [ 7.590534] systemd[1]: Reached target Preparation for Network. machine # [ 7.597287] systemd[1]: Starting DHCP Client... machine # [ 7.602797] systemd[1]: Starting Address configuration of eth1... machine # [ 7.608072] systemd[1]: Starting Extra networking commands.... machine # [ 7.783278] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660) machine # [ 7.799896] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0) machine # [ 7.675748] systemd[1]: Finished User Runtime Directory /run/user/1001. machine # [ 7.687120] systemd[1]: Starting User Manager for UID 1001... machine # [ 7.697809] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 7.698794] systemd[1]: Stopped Virtual Console Setup. machine # [ 7.701631] systemd[1]: Starting Virtual Console Setup... machine # [ 7.771051] (systemd)[619]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 7.788917] systemd-logind[516]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'. machine # [ 7.812389] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 7.896988] network-addresses-eth1-start[615]: adding address 192.168.1.1/24... done machine # [ 7.926146] network-addresses-eth1-start[615]: adding address 2001:db8:1::1/64... done machine # [ 7.972857] systemd[1]: Finished Address configuration of eth1. machine # [ 8.148830] kvm_amd: TSC scaling supported machine # [ 8.149259] kvm_amd: Nested Virtualization enabled machine # [ 8.153717] kvm_amd: Nested Paging enabled machine # [ 8.154135] kvm_amd: LBR virtualization supported machine # [ 8.157518] kvm_amd: Virtual VMLOAD VMSAVE supported machine # [ 8.158001] kvm_amd: Virtual GIF supported machine # [ 8.161321] kvm_amd: Virtual NMI enabled machine # [ 8.021785] systemd[619]: Queued start job for default target Main User Target. machine # [ 8.029102] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 8.031137] systemd[619]: Created slice User Application Slice. machine # [ 8.031978] systemd[619]: Started Daily Cleanup of User's Temporary Directories. machine # [ 8.036238] systemd[619]: Reached target Paths. machine # [ 8.036955] systemd[619]: Reached target Timers. machine # [ 8.045101] systemd[619]: Starting D-Bus User Message Bus Socket... machine # [ 8.045980] systemd[619]: Starting Create User Files and Directories... machine # [ 8.083682] systemd[619]: Finished Create User Files and Directories. machine # [ 8.103309] systemd[619]: Listening on D-Bus User Message Bus Socket. machine # [ 8.105184] systemd[619]: Reached target Sockets. machine # [ 8.105828] systemd[619]: Reached target Basic System. machine # [ 8.106600] systemd[1]: Started User Manager for UID 1001. machine # [ 8.107394] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 8.129434] systemd[1]: Finished Extra networking commands.. machine # [ 8.132361] dhcpcd[637]: dhcpcd-10.3.2 starting machine # [ 8.135706] systemd[1]: Reached target Network. machine # [ 8.136841] systemd[1]: Starting Permit User Sessions... machine # [ 8.148703] dhcpcd[697]: dev: loaded udev machine # [ 8.295079] EDAC MC: Ver: 3.0.0 machine # [ 8.152266] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 8.153198] systemd[619]: Reached target Main User Target. machine # [ 8.153920] systemd[619]: Startup finished in 348ms. machine # [ 8.309402] 8021q: 802.1Q VLAN Support v1.8 machine # [ 8.309862] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 8.199987] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 8.215710] systemd[1]: Finished Permit User Sessions. machine # [ 8.221126] systemd[1]: Started Getty on tty1. machine # [ 8.221800] systemd[1]: Reached target Login Prompts. machine # [ 8.398190] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 8.409442] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 8.410141] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 8.412275] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 8.413175] cfg80211: failed to load regulatory.db machine # [ 8.440953] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 8.298640] dhcpcd[697]: eth0: waiting for carrier machine # [ 8.299561] dhcpcd[697]: eth0: carrier acquired machine # [ 8.304977] dhcpcd[697]: DUID 00:01:00:01:32:3a:20:a8:52:54:00:12:34:56 machine # [ 8.306085] dhcpcd[697]: eth0: IAID 00:12:34:56 machine # [ 8.306772] dhcpcd[697]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 8.323452] systemd-vconsole-setup[621]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 8.326579] systemd[1]: Finished Virtual Console Setup. machine # [ 9.112462] dhcpcd[697]: eth0: soliciting a DHCP lease machine # [ 9.270247] NET: Registered PF_PACKET protocol family machine # [ 9.132126] dhcpcd[697]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 9.134377] dhcpcd[697]: eth0: probing address 10.0.2.15/24 machine # [ 10.123819] dhcpcd[697]: eth0: soliciting an IPv6 router machine # [ 10.126640] dhcpcd[697]: eth0: Router Advertisement from fe80::2 machine # [ 10.128771] dhcpcd[697]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 10.130969] dhcpcd[697]: eth0: adding route to fec0::/64 machine # [ 10.132887] dhcpcd[697]: eth0: adding default route via fe80::2 machine # [ 14.380559] dhcpcd[697]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 14.383170] dhcpcd[697]: eth0: adding route to 10.0.2.0/24 machine # [ 14.385282] dhcpcd[697]: eth0: adding default route via 10.0.2.2 machine # [ 14.468172] systemd[1]: Started DHCP Client. machine # [ 14.470215] systemd[1]: Reached target Multi-User System. machine # [ 14.470962] systemd[1]: Startup finished in 839ms (kernel) + 3.422s (initrd) + 10.208s (userspace) = 14.470s. machine: (finished: waiting for unit multi-user.target, in 15.67 seconds) machine: must succeed: /nix/store/yidcpw0fsqryxdbi00sgaskaw7wb9pps-stderr-runner /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/bin/switch-to-configuration test machine # Checking switch inhibitors... done machine # [ 14.916293] nixos[842]: switching to system configuration /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test machine # activating the configuration... machine # [ 14.919989] systemd[1]: Stopped target Remote File Systems. machine # [ 14.920923] systemd[1]: Stopped target Local File Systems. machine # setting up /etc... machine # [ 15.134738] systemd[1]: Reload requested from client PID 842 ('.switch-to-conf') (unit backdoor.service)... machine # [ 15.136357] systemd[1]: Reloading... machine # [ 15.372894] systemd[1]: Reloading finished in 234 ms. machine # reloading user units for usertest... machine # [ 15.402044] systemd[619]: Created slice User Core Session Slice. machine # [ 15.403592] systemd[619]: Starting D-Bus User Message Bus... machine # [ 15.423972] dbus-broker-launch[912]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/r9spxqyp1c7673bc4ad24fnmdicn6lhw-system-path/share/dbus-1/services/org.freedesktop.import1.service' machine # [ 15.426606] dbus-broker-launch[912]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/r9spxqyp1c7673bc4ad24fnmdicn6lhw-system-path/share/dbus-1/services/org.freedesktop.machine1.service' machine # [ 15.429085] dbus-broker-launch[912]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/r9spxqyp1c7673bc4ad24fnmdicn6lhw-system-path/share/dbus-1/services/org.freedesktop.portable1.service' machine # [ 15.431938] dbus-broker-launch[912]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/r9spxqyp1c7673bc4ad24fnmdicn6lhw-system-path/share/dbus-1/services/org.freedesktop.systemd1.service' machine # [ 15.434209] dbus-broker-launch[912]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored machine # [ 15.435721] dbus-broker-launch[912]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored machine # [ 15.437637] systemd[619]: Started D-Bus User Message Bus. machine # [ 15.440899] dbus-broker-launch[912]: Ready machine # [ 15.477984] systemd[619]: Reexecution requested from client PID 911 ('switch-to-confi')... machine # [ 15.479825] systemd[619]: Reexecuting. machine # [ 15.501141] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 15.574447] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 15.579592] systemd[619]: Reload requested from client PID 911 ('switch-to-confi')... machine # [ 15.581877] systemd[619]: Reloading... machine # [ 15.654254] systemd[619]: Reloading finished in 72 ms. machine # [ 15.659820] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 15.667065] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 15.667925] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 15.669881] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 15.689056] systemd[619]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 15.697455] systemd[1]: Starting Re-setup tmpfiles on a system that is already running.... machine # [ 15.766497] systemd[1]: Finished Re-setup tmpfiles on a system that is already running.. machine # [ 15.767943] systemd[1]: Reached target Reactivate sysinit units. machine # [ 15.769559] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 15.771197] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 15.773800] systemd[1]: Reached target Remote File Systems. machine # [ 15.778319] systemd[1]: Reached target Local File Systems. machine # [ 15.780163] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 15.783697] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 15.784517] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 15.786321] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 15.788264] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 15.789671] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 15.790799] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 15.929859] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 15.932284] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 15.935699] systemd[1]: Starting linger-users.service... machine # [ 15.962280] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 15.963672] systemd[1]: Finished linger-users.service. machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service machine # [ 16.223105] nixos[842]: finished switching to system configuration /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test machine: (finished: must succeed: /nix/store/yidcpw0fsqryxdbi00sgaskaw7wb9pps-stderr-runner /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/bin/switch-to-configuration test, in 1.51 seconds) machine: must succeed: rm /etc/NIXOS machine: (finished: must succeed: rm /etc/NIXOS, in 0.03 seconds) machine: must succeed: /nix/store/yidcpw0fsqryxdbi00sgaskaw7wb9pps-stderr-runner /nix/store/96bw1ds01ms6bx79r97szf460j1pfm84-nixos-system-other-test/bin/switch-to-configuration test machine # this should succeed (config: /nix/store/96bw1ds01ms6bx79r97szf460j1pfm84-nixos-system-other-test, action: test) machine # Checking switch inhibitors... done machine # [ 16.490888] nixos[989]: switching to system configuration /nix/store/96bw1ds01ms6bx79r97szf460j1pfm84-nixos-system-other-test machine # [ 16.493297] systemd[1]: Stopped target Remote File Systems. machine # [ 16.494911] systemd[1]: Stopped target Local File Systems. machine # activating the configuration... machine # removing user ‘usertest’ machine # setting up /etc... machine # [ 16.722962] systemd[1]: Reload requested from client PID 989 ('.switch-to-conf') (unit backdoor.service)... machine # [ 16.724556] systemd[1]: Reloading... machine # [ 16.964781] systemd[1]: Reloading finished in 239 ms. machine # reloading user units for usertest... machine # [ 17.030972] systemd[619]: Reexecution requested from client PID 1060 ('switch-to-confi')... machine # [ 17.033279] systemd[619]: Reexecuting. machine # [ 17.054273] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 17.128508] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 17.133629] systemd[619]: Reload requested from client PID 1060 ('switch-to-confi')... machine # [ 17.135056] systemd[619]: Reloading... machine # [ 17.206603] systemd[619]: Reloading finished in 70 ms. machine # [ 17.211816] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 17.219104] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 17.219943] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 17.221892] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 17.242061] systemd[619]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 17.245557] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 17.246624] systemd[1]: Stopping Reactivate sysinit units... machine # [ 17.247798] systemd[1]: Reached target Reactivate sysinit units. machine # reloading the following units: dbus-broker.service machine # [ 17.250979] dbus-broker-launch[499]: Caught SIGHUP, trigger reload. machine # [ 17.252525] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 17.257445] dbus-broker-launch[499]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 17.259198] dbus-broker-launch[499]: NSS returned no entry for 'systemd-timesync' machine # [ 17.260188] dbus-broker-launch[499]: Invalid user-name in /nix/store/ycj7ak1j6865prj5nmgrd46b1v2c9khg-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 17.270893] systemd[1]: Reloaded D-Bus System Message Bus. machine # restarting the following units: network-addresses-eth1.service machine # [ 17.275711] systemd[1]: Stopping Address configuration of eth1... machine # [ 17.295338] network-addresses-eth1-pre-stop[1072]: deleting address 192.168.1.1/24... done machine # [ 17.300525] network-addresses-eth1-pre-stop[1072]: deleting address 2001:db8:1::1/64... done machine # [ 17.305489] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 17.307064] systemd[1]: Stopped Address configuration of eth1. machine # [ 17.310159] systemd[1]: Starting Address configuration of eth1... machine # [ 17.337333] network-addresses-eth1-start[1077]: adding address 192.168.1.2/24... done machine # [ 17.342366] network-addresses-eth1-start[1077]: adding address 2001:db8:1::2/64... done machine # [ 17.349168] systemd[1]: Finished Address configuration of eth1. machine # [ 17.351510] systemd[1]: Reached target Remote File Systems. machine # [ 17.354874] systemd[1]: Reached target Local File Systems. machine # [ 17.357772] systemd[1]: Starting linger-users.service... machine # [ 17.363162] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 17.364318] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 17.371713] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 17.375741] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 17.376590] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 17.378215] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 17.380116] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 17.381544] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 17.382708] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 17.404615] linger-users-start[1091]: id: ‘usertest’: no such user machine # [ 17.406365] linger-users-start[1088]: Removing linger for missing user usertest machine # [ 17.413112] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 17.414169] systemd[1]: Finished linger-users.service. machine # [ 17.545054] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 17.546706] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 17.804733] nixos[989]: finished switching to system configuration /nix/store/96bw1ds01ms6bx79r97szf460j1pfm84-nixos-system-other-test machine: (finished: must succeed: /nix/store/yidcpw0fsqryxdbi00sgaskaw7wb9pps-stderr-runner /nix/store/96bw1ds01ms6bx79r97szf460j1pfm84-nixos-system-other-test/bin/switch-to-configuration test, in 1.55 seconds) subtest: services machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 18.041482] nixos[1155]: switching to system configuration /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test machine # [ 18.044721] systemd[1]: Stopped target Remote File Systems. machine # [ 18.046058] systemd[1]: Stopped target Local File Systems. machine # [ 18.254775] systemd[1]: Reload requested from client PID 1155 ('.switch-to-conf') (unit backdoor.service)... machine # [ 18.256467] systemd[1]: Reloading... machine # [ 18.464073] systemd[1]: Reloading finished in 206 ms. machine # [ 18.524361] systemd[619]: Reexecution requested from client PID 1225 ('switch-to-confi')... machine # [ 18.526795] systemd[619]: Reexecuting. machine # [ 18.547074] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 18.623789] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 18.628814] systemd[619]: Reload requested from client PID 1225 ('switch-to-confi')... machine # [ 18.630448] systemd[619]: Reloading... machine # [ 18.702042] systemd[619]: Reloading finished in 70 ms. machine # [ 18.707825] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 18.714227] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 18.716034] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 18.717520] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 18.736135] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 18.738909] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 18.740267] systemd[1]: Stopping Reactivate sysinit units... machine # [ 18.741285] systemd[1]: Reached target Reactivate sysinit units. machine # [ 18.743721] dbus-broker-launch[499]: Caught SIGHUP, trigger reload. machine # [ 18.745376] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 18.748827] dbus-broker-launch[499]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 18.750585] dbus-broker-launch[499]: NSS returned no entry for 'systemd-timesync' machine # [ 18.751590] dbus-broker-launch[499]: Invalid user-name in /nix/store/r9spxqyp1c7673bc4ad24fnmdicn6lhw-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 18.760700] systemd[1]: Reloaded D-Bus System Message Bus. machine # [ 18.766940] systemd[1]: Stopping Address configuration of eth1... machine # [ 18.784444] network-addresses-eth1-pre-stop[1237]: deleting address 192.168.1.2/24... done machine # [ 18.789221] network-addresses-eth1-pre-stop[1237]: deleting address 2001:db8:1::2/64... done machine # [ 18.793851] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 18.795431] systemd[1]: Stopped Address configuration of eth1. machine # [ 18.798532] systemd[1]: Starting Address configuration of eth1... machine # [ 18.825912] network-addresses-eth1-start[1242]: adding address 192.168.1.1/24... done machine # [ 18.831262] network-addresses-eth1-start[1242]: adding address 2001:db8:1::1/64... done machine # [ 18.838067] systemd[1]: Finished Address configuration of eth1. machine # [ 18.841737] systemd[1]: Reached target Remote File Systems. machine # [ 18.844400] systemd[1]: Starting linger-users.service... machine # [ 18.848500] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 18.849714] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 18.853890] systemd[1]: Reached target Local File Systems. machine # [ 18.855824] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 18.860263] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 18.862312] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 18.863992] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 18.865952] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 18.867337] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 18.868464] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 18.899055] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 18.900985] systemd[1]: Finished linger-users.service. machine # [ 19.016300] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 19.017860] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 19.276282] nixos[1155]: finished switching to system configuration /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.47 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 19.487582] nixos[1311]: switching to system configuration /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test machine # [ 19.489875] systemd[1]: Stopped target Local File Systems. machine # [ 19.492108] systemd[1]: Stopped target Remote File Systems. machine # [ 19.697539] systemd[1]: Reload requested from client PID 1311 ('.switch-to-conf') (unit backdoor.service)... machine # [ 19.699299] systemd[1]: Reloading... machine # [ 19.929968] systemd[1]: Reloading finished in 229 ms. machine # [ 19.992388] systemd[619]: Reexecution requested from client PID 1380 ('switch-to-confi')... machine # [ 19.994358] systemd[619]: Reexecuting. machine # [ 20.015038] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 20.092622] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 20.097832] systemd[619]: Reload requested from client PID 1380 ('switch-to-confi')... machine # [ 20.099097] systemd[619]: Reloading... machine # [ 20.172734] systemd[619]: Reloading finished in 71 ms. machine # [ 20.177828] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 20.184176] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 20.185913] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 20.187247] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 20.206099] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 20.209045] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 20.210543] systemd[1]: Stopping Reactivate sysinit units... machine # [ 20.211517] systemd[1]: Reached target Reactivate sysinit units. machine # [ 20.213351] systemd[1]: Reached target Remote File Systems. machine # [ 20.215914] systemd[1]: Starting linger-users.service... machine # [ 20.219482] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 20.220680] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 20.226199] systemd[1]: Reached target Local File Systems. machine # [ 20.226956] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 20.230665] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 20.232113] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 20.233371] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 20.235374] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 20.236813] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 20.237984] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 20.282922] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 20.284274] systemd[1]: Finished linger-users.service. machine # [ 20.391911] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 20.393726] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 20.650630] nixos[1311]: finished switching to system configuration /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.37 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1 machine # [ 20.877713] nixos[1451]: switching to system configuration /nix/store/x6gvkqs67v1mgwgqqnnwlpblvdqqz8yz-nixos-system-machine-test machine # [ 20.880541] systemd[1]: Stopped target Local File Systems. machine # [ 20.882666] systemd[1]: Stopped target Remote File Systems. machine # [ 21.130255] systemd[1]: Reload requested from client PID 1451 ('.switch-to-conf') (unit backdoor.service)... machine # [ 21.131935] systemd[1]: Reloading... machine # [ 21.569188] systemd[1]: Reloading finished in 435 ms. machine # [ 21.630294] systemd[619]: Reexecution requested from client PID 1521 ('switch-to-confi')... machine # [ 21.631554] systemd[619]: Reexecuting. machine # [ 21.653082] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 21.726450] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 21.731651] systemd[619]: Reload requested from client PID 1521 ('switch-to-confi')... machine # [ 21.733070] systemd[619]: Reloading... machine # [ 21.806294] systemd[619]: Reloading finished in 72 ms. machine # [ 21.811828] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 21.817780] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 21.819326] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 21.821190] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 21.840427] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 21.843503] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 21.844581] systemd[1]: Stopping Reactivate sysinit units... machine # [ 21.845733] systemd[1]: Reached target Reactivate sysinit units. machine # [ 21.849175] systemd[1]: Reached target Local File Systems. machine # [ 21.851060] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 21.854217] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 21.854994] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 21.857128] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 21.859093] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 21.860543] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 21.861679] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 21.865666] systemd[1]: Reached target Remote File Systems. machine # [ 21.870781] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 21.872727] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 21.997683] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 21.999306] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 22.003126] systemd[1]: Starting linger-users.service... machine # [ 22.005478] systemd[1]: Starting test.service... machine # [ 22.027890] systemd[1]: Finished test.service. machine # [ 22.041335] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 22.043242] systemd[1]: Finished linger-users.service. machine # [ 22.299688] nixos[1451]: finished switching to system configuration /nix/store/x6gvkqs67v1mgwgqqnnwlpblvdqqz8yz-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1, in 1.65 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1 machine # [ 22.493915] nixos[1594]: switching to system configuration /nix/store/x6gvkqs67v1mgwgqqnnwlpblvdqqz8yz-nixos-system-machine-test machine # [ 22.497259] systemd[1]: Stopped target Remote File Systems. machine # [ 22.498334] systemd[1]: Stopped target Local File Systems. machine # [ 22.705571] systemd[1]: Reload requested from client PID 1594 ('.switch-to-conf') (unit backdoor.service)... machine # [ 22.707166] systemd[1]: Reloading... machine # [ 22.939960] systemd[1]: Reloading finished in 230 ms. machine # [ 22.998720] systemd[619]: Reexecution requested from client PID 1663 ('switch-to-confi')... machine # [ 23.001270] systemd[619]: Reexecuting. machine # [ 23.020981] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.094458] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.099619] systemd[619]: Reload requested from client PID 1663 ('switch-to-confi')... machine # [ 23.100839] systemd[619]: Reloading... machine # [ 23.171763] systemd[619]: Reloading finished in 69 ms. machine # [ 23.176832] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.185064] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 23.185948] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 23.188135] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 23.207217] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 23.210309] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 23.211808] systemd[1]: Stopping Reactivate sysinit units... machine # [ 23.213124] systemd[1]: Reached target Reactivate sysinit units. machine # [ 23.214681] systemd[1]: Reached target Remote File Systems. machine # [ 23.217298] systemd[1]: Reached target Local File Systems. machine # [ 23.223070] systemd[1]: Starting linger-users.service... machine # [ 23.224671] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 23.229281] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 23.230580] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 23.233106] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 23.235057] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 23.236458] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 23.237620] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 23.242901] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 23.244118] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 23.282902] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 23.284773] systemd[1]: Finished linger-users.service. machine # [ 23.387805] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 23.390560] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 23.644239] nixos[1594]: finished switching to system configuration /nix/store/x6gvkqs67v1mgwgqqnnwlpblvdqqz8yz-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1, in 1.34 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceDifferentDescription/bin/switch-to-configuration test 2>&1 machine # [ 23.869300] nixos[1734]: switching to system configuration /nix/store/ibmhsdax714vr602whhrypk9bapgsygw-nixos-system-machine-test machine # [ 23.872428] systemd[1]: Stopped target Remote File Systems. machine # [ 23.873588] systemd[1]: Stopped target Local File Systems. machine # [ 24.123956] systemd[1]: Reload requested from client PID 1734 ('.switch-to-conf') (unit backdoor.service)... machine # [ 24.125653] systemd[1]: Reloading... machine # [ 24.377782] systemd[1]: Reloading finished in 250 ms. machine # [ 24.454285] systemd[619]: Reexecution requested from client PID 1803 ('switch-to-confi')... machine # [ 24.455564] systemd[619]: Reexecuting. machine # [ 24.476083] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 24.547968] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 24.553887] systemd[619]: Reload requested from client PID 1803 ('switch-to-confi')... machine # [ 24.555499] systemd[619]: Reloading... machine # [ 24.629402] systemd[619]: Reloading finished in 72 ms. machine # [ 24.633837] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 24.640934] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 24.641848] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 24.643483] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 24.666651] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 24.669612] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 24.670936] systemd[1]: Stopping Reactivate sysinit units... machine # [ 24.671889] systemd[1]: Reached target Reactivate sysinit units. machine # [ 24.674077] systemd[1]: Reached target Local File Systems. machine # [ 24.676198] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 24.679207] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 24.679943] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 24.681984] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 24.683983] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 24.685454] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.689294] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.693508] systemd[1]: Reached target Remote File Systems. machine # [ 24.698043] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.699604] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 24.822752] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 24.826177] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 24.828266] systemd[1]: Starting linger-users.service... machine # [ 24.855352] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 24.857540] systemd[1]: Finished linger-users.service. machine # [ 25.114309] nixos[1734]: finished switching to system configuration /nix/store/ibmhsdax714vr602whhrypk9bapgsygw-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceDifferentDescription/bin/switch-to-configuration test 2>&1, in 1.47 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1 machine # [ 25.311706] nixos[1875]: switching to system configuration /nix/store/3vwhbi9l100rcjd6f1xr90q0n4pjm127-nixos-system-machine-test machine # [ 25.314277] systemd[1]: Stopped target Local File Systems. machine # [ 25.316360] systemd[1]: test.service: Deactivated successfully. machine # [ 25.317241] systemd[1]: Stopped Test unit. machine # [ 25.318278] systemd[1]: Stopped target Remote File Systems. machine # [ 25.542394] systemd[1]: Reload requested from client PID 1875 ('.switch-to-conf') (unit backdoor.service)... machine # [ 25.544186] systemd[1]: Reloading... machine # [ 25.783844] systemd[1]: Reloading finished in 239 ms. machine # [ 25.849315] systemd[619]: Reexecution requested from client PID 1944 ('switch-to-confi')... machine # [ 25.851592] systemd[619]: Reexecuting. machine # [ 25.873072] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 25.946177] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 25.951689] systemd[619]: Reload requested from client PID 1944 ('switch-to-confi')... machine # [ 25.953227] systemd[619]: Reloading... machine # [ 26.026038] systemd[619]: Reloading finished in 72 ms. machine # [ 26.031833] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 26.037831] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 26.038887] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 26.041278] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 26.061622] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 26.064595] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 26.065880] systemd[1]: Stopping Reactivate sysinit units... machine # [ 26.066950] systemd[1]: Reached target Reactivate sysinit units. machine # [ 26.070495] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.072139] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 26.076377] systemd[1]: Reached target Local File Systems. machine # [ 26.078681] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 26.082064] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 26.082844] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 26.086060] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 26.088223] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 26.090544] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.091717] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.094280] systemd[1]: Reached target Remote File Systems. machine # [ 26.222415] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 26.226071] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 26.227708] systemd[1]: Starting linger-users.service... machine # [ 26.230219] systemd[1]: Starting test.service... machine # [ 26.254185] systemd[1]: Finished test.service. machine # [ 26.266738] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 26.268251] systemd[1]: Finished linger-users.service. machine # [ 26.524693] nixos[1875]: finished switching to system configuration /nix/store/3vwhbi9l100rcjd6f1xr90q0n4pjm127-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1, in 1.41 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 26.756400] nixos[2017]: switching to system configuration /nix/store/58qnsk9s7jd99bn1nr3fgl7i5v3xfsvr-nixos-system-machine-test machine # [ 26.758877] systemd[1]: Stopped target Remote File Systems. machine # [ 26.760509] systemd[1]: Stopped target Local File Systems. machine # [ 26.983386] systemd[1]: Reload requested from client PID 2017 ('.switch-to-conf') (unit backdoor.service)... machine # [ 26.984946] systemd[1]: Reloading... machine # [ 27.222950] systemd[1]: Reloading finished in 237 ms. machine # [ 27.284543] systemd[619]: Reexecution requested from client PID 2086 ('switch-to-confi')... machine # [ 27.286555] systemd[619]: Reexecuting. machine # [ 27.307268] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.378878] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.384832] systemd[619]: Reload requested from client PID 2086 ('switch-to-confi')... machine # [ 27.386779] systemd[619]: Reloading... machine # [ 27.479546] systemd[619]: Reloading finished in 91 ms. machine # [ 27.484887] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.490937] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 27.491920] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 27.494460] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 27.512834] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 27.515695] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 27.516981] systemd[1]: Stopping Reactivate sysinit units... machine # [ 27.518276] systemd[1]: Reached target Reactivate sysinit units. machine # [ 27.519678] systemd[1]: test.service: Deactivated successfully. machine # [ 27.521165] systemd[1]: Stopped test.service. machine # [ 27.521737] systemd[1]: Stopping test.service... machine # [ 27.523528] systemd[1]: Starting test.service... machine # [ 27.537256] systemd[1]: Finished test.service. machine # [ 27.541489] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.543116] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 27.546668] systemd[1]: Reached target Remote File Systems. machine # [ 27.548331] systemd[1]: Reached target Local File Systems. machine # [ 27.552672] systemd[1]: Starting linger-users.service... machine # [ 27.559142] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 27.563403] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 27.564224] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 27.565965] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 27.567954] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 27.570370] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.571928] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.607486] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 27.609596] systemd[1]: Finished linger-users.service. machine # [ 27.719964] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 27.721779] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 27.976279] nixos[2017]: finished switching to system configuration /nix/store/58qnsk9s7jd99bn1nr3fgl7i5v3xfsvr-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 1.45 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceReload/bin/switch-to-configuration test 2>&1 machine # [ 28.205153] nixos[2160]: switching to system configuration /nix/store/sh9wiqzs5i3pcpwlah0dwy4gp6il9rg1-nixos-system-machine-test machine # [ 28.207466] systemd[1]: Stopped target Local File Systems. machine # [ 28.209245] systemd[1]: Stopped target Remote File Systems. machine # [ 28.436125] systemd[1]: Reload requested from client PID 2160 ('.switch-to-conf') (unit backdoor.service)... machine # [ 28.437747] systemd[1]: Reloading... machine # [ 28.684892] systemd[1]: Reloading finished in 246 ms. machine # [ 28.748899] systemd[619]: Reexecution requested from client PID 2229 ('switch-to-confi')... machine # [ 28.751354] systemd[619]: Reexecuting. machine # [ 28.772212] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 28.846277] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 28.851217] systemd[619]: Reload requested from client PID 2229 ('switch-to-confi')... machine # [ 28.853239] systemd[619]: Reloading... machine # [ 28.927771] systemd[619]: Reloading finished in 73 ms. machine # [ 28.932829] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 28.939314] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 28.940255] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 28.942226] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 28.962104] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 28.965150] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 28.965990] systemd[1]: Stopping Reactivate sysinit units... machine # [ 28.967375] systemd[1]: Reached target Reactivate sysinit units. machine # [ 28.970767] systemd[1]: Reloading test.service... machine # [ 28.987236] systemd[1]: Reloaded test.service. machine # [ 28.989800] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 28.991700] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 28.996567] systemd[1]: Reached target Remote File Systems. machine # [ 28.999311] systemd[1]: Starting linger-users.service... machine # [ 29.000963] systemd[1]: Reached target Local File Systems. machine # [ 29.009433] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 29.013337] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 29.014872] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 29.016317] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 29.019173] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 29.020560] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 29.021740] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 29.054396] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 29.056658] systemd[1]: Finished linger-users.service. machine # [ 29.164106] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 29.165677] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 29.423387] nixos[2160]: finished switching to system configuration /nix/store/sh9wiqzs5i3pcpwlah0dwy4gp6il9rg1-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceReload/bin/switch-to-configuration test 2>&1, in 1.45 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1 machine # [ 29.654152] nixos[2302]: switching to system configuration /nix/store/681igddh6k2migcwdmbdjw0vivlgm5la-nixos-system-machine-test machine # [ 29.656613] systemd[1]: Stopped target Remote File Systems. machine # [ 29.658396] systemd[1]: Stopped target Local File Systems. machine # [ 29.884742] systemd[1]: Reload requested from client PID 2302 ('.switch-to-conf') (unit backdoor.service)... machine # [ 29.886427] systemd[1]: Reloading... machine # [ 30.126896] systemd[1]: Reloading finished in 239 ms. machine # [ 30.187332] systemd[619]: Reexecution requested from client PID 2371 ('switch-to-confi')... machine # [ 30.189151] systemd[619]: Reexecuting. machine # [ 30.208940] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 30.280085] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 30.285130] systemd[619]: Reload requested from client PID 2371 ('switch-to-confi')... machine # [ 30.286562] systemd[619]: Reloading... machine # [ 30.358328] systemd[619]: Reloading finished in 70 ms. machine # [ 30.363093] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 30.370226] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 30.371122] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 30.373564] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 30.391883] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 30.394723] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 30.396362] systemd[1]: Stopping Reactivate sysinit units... machine # [ 30.397395] systemd[1]: Reached target Reactivate sysinit units. machine # [ 30.399556] systemd[1]: Reached target Remote File Systems. machine # [ 30.402383] systemd[1]: Starting linger-users.service... machine # [ 30.407066] systemd[1]: Reached target Local File Systems. machine # [ 30.407832] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 30.411559] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 30.412456] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 30.415630] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 30.417697] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 30.419146] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 30.420388] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 30.426267] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 30.427466] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 30.465692] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 30.467430] systemd[1]: Finished linger-users.service. machine # [ 30.570798] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 30.572426] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 30.830393] nixos[2302]: finished switching to system configuration /nix/store/681igddh6k2migcwdmbdjw0vivlgm5la-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1, in 1.41 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration dry-activate 2>&1 machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration dry-activate 2>&1, in 0.28 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 31.299590] nixos[2453]: switching to system configuration /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test machine # [ 31.302131] systemd[1]: Stopped target Local File Systems. machine # [ 31.304467] systemd[1]: test.service: Deactivated successfully. machine # [ 31.305511] systemd[1]: Stopped test.service. machine # [ 31.306449] systemd[1]: Stopped target Remote File Systems. machine # [ 31.529289] systemd[1]: Reload requested from client PID 2453 ('.switch-to-conf') (unit backdoor.service)... machine # [ 31.530970] systemd[1]: Reloading... machine # [ 31.759812] systemd[1]: Reloading finished in 227 ms. machine # [ 31.821858] systemd[619]: Reexecution requested from client PID 2522 ('switch-to-confi')... machine # [ 31.824237] systemd[619]: Reexecuting. machine # [ 31.843071] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 31.915868] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 31.922066] systemd[619]: Reload requested from client PID 2522 ('switch-to-confi')... machine # [ 31.923302] systemd[619]: Reloading... machine # [ 31.994928] systemd[619]: Reloading finished in 70 ms. machine # [ 32.000829] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.007060] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 32.007936] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 32.010082] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 32.028970] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 32.031776] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 32.033192] systemd[1]: Stopping Reactivate sysinit units... machine # [ 32.033942] systemd[1]: Reached target Reactivate sysinit units. machine # [ 32.036800] systemd[1]: Reached target Remote File Systems. machine # [ 32.039770] systemd[1]: Starting linger-users.service... machine # [ 32.046935] systemd[1]: Reached target Local File Systems. machine # [ 32.050445] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 32.052350] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 32.055874] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 32.060196] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 32.060946] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 32.063591] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 32.065906] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 32.067319] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 32.070156] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 32.104082] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 32.106090] systemd[1]: Finished linger-users.service. machine # [ 32.211717] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 32.213947] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 32.470873] nixos[2453]: finished switching to system configuration /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.36 seconds) machine: must succeed: /nix/store/r8hml2sz3792m7s2nfnxc8mzqn87y85a-separate-activation-script/bin/switch-to-configuration test 2>&1 machine # [ 32.682853] nixos[2593]: switching to system configuration /nix/store/w9j04q1vp7wnqx5njxyi5hk740a5mzmv-nixos-system-machine-test machine # [ 32.686508] systemd[1]: Stopped target Local File Systems. machine # [ 32.687349] systemd[1]: Stopped target Remote File Systems. machine # [ 32.915971] systemd[1]: Reload requested from client PID 2593 ('.switch-to-conf') (unit backdoor.service)... machine # [ 32.917784] systemd[1]: Reloading... machine # [ 33.144395] systemd[1]: Reloading finished in 225 ms. machine # [ 33.214747] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 33.217064] systemd[619]: Reexecution requested from client PID 2662 ('switch-to-confi')... machine # [ 33.218276] systemd[619]: Reexecuting. machine # [ 33.239126] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.316367] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.321708] systemd[619]: Reload requested from client PID 2662 ('switch-to-confi')... machine # [ 33.323053] systemd[619]: Reloading... machine # [ 33.392097] systemd[619]: Reloading finished in 67 ms. machine # [ 33.397094] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.405067] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 33.406425] systemd[1]: Stopping Reactivate sysinit units... machine # [ 33.407326] systemd[1]: Reached target Reactivate sysinit units. machine # [ 33.408473] systemd[1]: Reached target Remote File Systems. machine # [ 33.415165] systemd[1]: Starting linger-users.service... machine # [ 33.417128] systemd[1]: Starting test.service... machine # [ 33.425223] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.426415] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 33.433403] systemd[1]: Reached target Local File Systems. machine # [ 33.435706] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 33.440312] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 33.442183] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 33.443287] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 33.445186] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 33.449079] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.451203] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.467566] systemd[1]: Finished test.service. machine # [ 33.489693] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 33.490789] systemd[1]: Finished linger-users.service. machine # [ 33.597213] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 33.598941] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 33.858827] nixos[2593]: finished switching to system configuration /nix/store/w9j04q1vp7wnqx5njxyi5hk740a5mzmv-nixos-system-machine-test machine: (finished: must succeed: /nix/store/r8hml2sz3792m7s2nfnxc8mzqn87y85a-separate-activation-script/bin/switch-to-configuration test 2>&1, in 1.39 seconds) machine: must succeed: ! test -e /run/current-system/activate machine: (finished: must succeed: ! test -e /run/current-system/activate, in 0.02 seconds) machine: must succeed: ! test -e /run/current-system/dry-activate machine: (finished: must succeed: ! test -e /run/current-system/dry-activate, in 0.01 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.01 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/unitWithMultilineValue/bin/switch-to-configuration test 2>&1 machine # [ 34.114993] nixos[2742]: switching to system configuration /nix/store/0w70lkgicvn2mycb5a93akcvj5qfa16h-nixos-system-machine-test machine # [ 34.117324] systemd[1]: Stopped target Local File Systems. machine # [ 34.118953] systemd[1]: Stopped target Remote File Systems. machine # [ 34.120532] systemd[1]: test.service: Deactivated successfully. machine # [ 34.121992] systemd[1]: Stopped test.service. machine # [ 34.346270] systemd[1]: Reload requested from client PID 2742 ('.switch-to-conf') (unit backdoor.service)... machine # [ 34.347890] systemd[1]: Reloading... machine # [ 34.588815] systemd[1]: Reloading finished in 239 ms. machine # [ 34.649474] systemd[619]: Reexecution requested from client PID 2811 ('switch-to-confi')... machine # [ 34.651831] systemd[619]: Reexecuting. machine # [ 34.672046] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.744177] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.749707] systemd[619]: Reload requested from client PID 2811 ('switch-to-confi')... machine # [ 34.751980] systemd[619]: Reloading... machine # [ 34.824334] systemd[619]: Reloading finished in 72 ms. machine # [ 34.829841] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.836707] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 34.857068] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 34.860073] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 34.860919] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 34.863798] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 34.865322] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 34.868147] systemd[1]: Stopping Reactivate sysinit units... machine # [ 34.868878] systemd[1]: Reached target Reactivate sysinit units. machine # [ 34.875611] systemd[1]: Reached target Remote File Systems. machine # [ 34.877444] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.879707] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 34.882217] systemd[1]: Starting linger-users.service... machine # [ 34.899069] systemd[1]: Started test.service. machine # [ 34.902877] systemd[1]: Reached target Local File Systems. machine # [ 34.911500] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 34.916635] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 34.920605] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 34.921577] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 34.923272] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 34.925303] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 34.926753] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.927965] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.950685] systemd[1]: test.service: Deactivated successfully. machine # [ 34.968791] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 34.971049] systemd[1]: Finished linger-users.service. machine # [ 35.082915] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 35.084578] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 35.339796] nixos[2742]: finished switching to system configuration /nix/store/0w70lkgicvn2mycb5a93akcvj5qfa16h-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/unitWithMultilineValue/bin/switch-to-configuration test 2>&1, in 1.43 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/unitWithBackslash/bin/switch-to-configuration test 2>&1 machine # [ 35.546401] nixos[2887]: switching to system configuration /nix/store/isaaqppvs7n7d0ab9ms1z7nnr81fcc2h-nixos-system-machine-test machine # [ 35.548753] systemd[1]: Stopped target Local File Systems. machine # [ 35.550630] systemd[1]: Stopped target Remote File Systems. machine # [ 35.776649] systemd[1]: Reload requested from client PID 2887 ('.switch-to-conf') (unit backdoor.service)... machine # [ 35.778376] systemd[1]: Reloading... machine # [ 36.020693] systemd[1]: Reloading finished in 241 ms. machine # [ 36.082874] systemd[619]: Reexecution requested from client PID 2956 ('switch-to-confi')... machine # [ 36.085311] systemd[619]: Reexecuting. machine # [ 36.109046] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.181695] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.187238] systemd[619]: Reload requested from client PID 2956 ('switch-to-confi')... machine # [ 36.189120] systemd[619]: Reloading... machine # [ 36.260775] systemd[619]: Reloading finished in 70 ms. machine # [ 36.265829] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.271798] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 36.272814] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 36.275255] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 36.293805] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 36.296711] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 36.298428] systemd[1]: Stopping Reactivate sysinit units... machine # [ 36.299523] systemd[1]: Reached target Reactivate sysinit units. machine # [ 36.303125] systemd[1]: Reached target Remote File Systems. machine # [ 36.306121] systemd[1]: Starting escaped\x2ddash.service... machine # [ 36.307773] systemd[1]: Starting linger-users.service... machine # [ 36.318097] systemd[1]: Reached target Local File Systems. machine # [ 36.318856] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 36.322249] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 36.323936] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 36.325222] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 36.328644] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 36.331176] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.333464] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.341029] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.342226] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 36.362221] systemd[1]: Finished escaped\x2ddash.service. machine # [ 36.382367] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 36.384175] systemd[1]: Finished linger-users.service. machine # [ 36.493770] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 36.495547] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 36.753388] nixos[2887]: finished switching to system configuration /nix/store/isaaqppvs7n7d0ab9ms1z7nnr81fcc2h-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/unitWithBackslash/bin/switch-to-configuration test 2>&1, in 1.42 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/unitWithBackslashModified/bin/switch-to-configuration test 2>&1 machine # [ 36.994106] nixos[3029]: switching to system configuration /nix/store/4jz8x4326pwcqnnwyfbrpv4aaqskdpz0-nixos-system-machine-test machine # [ 36.997186] systemd[1]: escaped\x2ddash.service: Deactivated successfully. machine # [ 36.998283] systemd[1]: Stopped escaped\x2ddash.service. machine # [ 36.999385] systemd[1]: Stopped target Remote File Systems. machine # [ 37.000878] systemd[1]: Stopped target Local File Systems. machine # [ 37.261219] systemd[1]: Reload requested from client PID 3029 ('.switch-to-conf') (unit backdoor.service)... machine # [ 37.262880] systemd[1]: Reloading... machine # [ 37.535472] systemd[1]: Reloading finished in 271 ms. machine # [ 37.598308] systemd[619]: Reexecution requested from client PID 3099 ('switch-to-confi')... machine # [ 37.600469] systemd[619]: Reexecuting. machine # [ 37.621159] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.694714] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.700194] systemd[619]: Reload requested from client PID 3099 ('switch-to-confi')... machine # [ 37.701580] systemd[619]: Reloading... machine # [ 37.766847] systemd[619]: Reloading finished in 63 ms. machine # [ 37.771825] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.777848] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 37.778863] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 37.781339] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 37.801197] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 37.804062] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 37.805654] systemd[1]: Stopping Reactivate sysinit units... machine # [ 37.806809] systemd[1]: Reached target Reactivate sysinit units. machine # [ 37.809888] systemd[1]: Starting escaped\x2ddash.service... machine # [ 37.817165] systemd[1]: Reached target Local File Systems. machine # [ 37.819153] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 37.822152] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 37.822943] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 37.825662] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 37.828643] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 37.831143] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.834403] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.835656] systemd[1]: Reached target Remote File Systems. machine # [ 37.842800] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.845087] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 37.855640] systemd[1]: Finished escaped\x2ddash.service. machine # [ 37.971517] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 37.973189] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 37.976777] systemd[1]: Starting linger-users.service... machine # [ 38.004363] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 38.005791] systemd[1]: Finished linger-users.service. machine # [ 38.262663] nixos[3029]: finished switching to system configuration /nix/store/4jz8x4326pwcqnnwyfbrpv4aaqskdpz0-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/unitWithBackslashModified/bin/switch-to-configuration test 2>&1, in 1.51 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/unitStartingWithDash/bin/switch-to-configuration test 2>&1 machine # [ 38.495395] nixos[3172]: switching to system configuration /nix/store/lpggzqb4jqwa7rhrbi0qd4293fsfkf14-nixos-system-machine-test machine # [ 38.497984] systemd[1]: Stopped target Local File Systems. machine # [ 38.499546] systemd[1]: Stopped target Remote File Systems. machine # [ 38.500991] systemd[1]: escaped\x2ddash.service: Deactivated successfully. machine # [ 38.502561] systemd[1]: Stopped escaped\x2ddash.service. machine # [ 38.727224] systemd[1]: Reload requested from client PID 3172 ('.switch-to-conf') (unit backdoor.service)... machine # [ 38.728854] systemd[1]: Reloading... machine # [ 38.974089] systemd[1]: Reloading finished in 243 ms. machine # [ 39.035450] systemd[619]: Reexecution requested from client PID 3241 ('switch-to-confi')... machine # [ 39.037766] systemd[619]: Reexecuting. machine # [ 39.059175] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.132339] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.137789] systemd[619]: Reload requested from client PID 3241 ('switch-to-confi')... machine # [ 39.140146] systemd[619]: Reloading... machine # [ 39.212121] systemd[619]: Reloading finished in 72 ms. machine # [ 39.217847] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.224252] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 39.225458] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 39.228087] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 39.247266] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 39.250660] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 39.252305] systemd[1]: Stopping Reactivate sysinit units... machine # [ 39.253337] systemd[1]: Reached target Reactivate sysinit units. machine # [ 39.258100] systemd[1]: Reached target Local File Systems. machine # [ 39.260428] systemd[1]: Reached target Remote File Systems. machine # [ 39.261356] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 39.264257] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 39.265908] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 39.267246] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 39.269500] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 39.271183] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.275234] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.278587] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.279985] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 39.408225] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 39.410163] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 39.413928] systemd[1]: Starting -.service... machine # [ 39.416751] systemd[1]: Starting linger-users.service... machine # [ 39.442583] systemd[1]: Finished -.service. machine # [ 39.454850] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 39.457353] systemd[1]: Finished linger-users.service. machine # [ 39.714519] nixos[3172]: finished switching to system configuration /nix/store/lpggzqb4jqwa7rhrbi0qd4293fsfkf14-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/unitStartingWithDash/bin/switch-to-configuration test 2>&1, in 1.45 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/unitStartingWithDashModified/bin/switch-to-configuration test 2>&1 machine # [ 39.945667] nixos[3314]: switching to system configuration /nix/store/32f988l8f5x9samjp8ir911m9shlhz77-nixos-system-machine-test machine # [ 39.947898] systemd[1]: Stopped target Local File Systems. machine # [ 39.949528] systemd[1]: Stopped target Remote File Systems. machine # [ 40.170943] systemd[1]: Reload requested from client PID 3314 ('.switch-to-conf') (unit backdoor.service)... machine # [ 40.172615] systemd[1]: Reloading... machine # [ 40.435846] systemd[1]: Reloading finished in 261 ms. machine # [ 40.522734] systemd[619]: Reexecution requested from client PID 3383 ('switch-to-confi')... machine # [ 40.524427] systemd[619]: Reexecuting. machine # [ 40.545090] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 40.621074] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 40.626500] systemd[619]: Reload requested from client PID 3383 ('switch-to-confi')... machine # [ 40.627854] systemd[619]: Reloading... machine # [ 40.700691] systemd[619]: Reloading finished in 71 ms. machine # [ 40.705828] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 40.711913] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 40.713903] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 40.715433] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 40.734229] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 40.737109] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 40.738542] systemd[1]: Stopping Reactivate sysinit units... machine # [ 40.739573] systemd[1]: Reached target Reactivate sysinit units. machine # [ 40.742566] systemd[1]: Reloading -.service... machine # [ 40.756450] systemd[1]: Reloaded -.service. machine # [ 40.758322] systemd[1]: Reached target Local File Systems. machine # [ 40.761258] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 40.763165] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 40.766687] systemd[1]: Reached target Remote File Systems. machine # [ 40.769130] systemd[1]: Starting linger-users.service... machine # [ 40.779797] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 40.783215] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 40.783987] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 40.786158] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 40.789049] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 40.792092] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 40.793329] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 40.827487] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 40.830237] systemd[1]: Finished linger-users.service. machine # [ 40.936083] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 40.938070] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 41.195716] nixos[3314]: finished switching to system configuration /nix/store/32f988l8f5x9samjp8ir911m9shlhz77-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/unitStartingWithDashModified/bin/switch-to-configuration test 2>&1, in 1.48 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/unitWithRequirement/bin/switch-to-configuration test 2>&1 machine # [ 41.438506] nixos[3457]: switching to system configuration /nix/store/vjaan6gc1zcb324135nfqhibxccvdc2k-nixos-system-machine-test machine # [ 41.441560] systemd[1]: -.service: Deactivated successfully. machine # [ 41.442525] systemd[1]: Stopped -.service. machine # [ 41.445292] systemd[1]: Stopped target Local File Systems. machine # [ 41.446302] systemd[1]: Stopped target Remote File Systems. machine # [ 41.676511] systemd[1]: Reload requested from client PID 3457 ('.switch-to-conf') (unit backdoor.service)... machine # [ 41.678332] systemd[1]: Reloading... machine # [ 41.905071] systemd[1]: Reloading finished in 224 ms. machine # [ 41.960886] systemd[619]: Reexecution requested from client PID 3526 ('switch-to-confi')... machine # [ 41.962986] systemd[619]: Reexecuting. machine # [ 41.984045] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.060262] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.065363] systemd[619]: Reload requested from client PID 3526 ('switch-to-confi')... machine # [ 42.066783] systemd[619]: Reloading... machine # [ 42.138915] systemd[619]: Reloading finished in 70 ms. machine # [ 42.144839] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.151845] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 42.152737] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 42.154426] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 42.175884] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 42.178911] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 42.180345] systemd[1]: Stopping Reactivate sysinit units... machine # [ 42.182030] systemd[1]: Reached target Reactivate sysinit units. machine # [ 42.182951] systemd[1]: Reached target Local File Systems. machine # [ 42.184847] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 42.191067] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 42.191888] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 42.194125] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 42.196110] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 42.197567] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 42.198700] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 42.205921] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 42.207586] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 42.212639] systemd[1]: Reached target Remote File Systems. machine # [ 42.337281] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 42.338902] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 42.343112] systemd[1]: Starting linger-users.service... machine # [ 42.346085] systemd[1]: Starting required-service.service... machine # [ 42.349396] systemd[1]: Starting test-service.service... machine # [ 42.381151] systemd[1]: Finished required-service.service. machine # [ 42.384031] systemd[1]: Finished test-service.service. machine # [ 42.397569] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 42.399866] systemd[1]: Finished linger-users.service. machine # [ 42.656560] nixos[3457]: finished switching to system configuration /nix/store/vjaan6gc1zcb324135nfqhibxccvdc2k-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/unitWithRequirement/bin/switch-to-configuration test 2>&1, in 1.46 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/unitWithRequirementModified/bin/switch-to-configuration test 2>&1 machine # [ 42.895859] nixos[3601]: switching to system configuration /nix/store/7acmw3fpvi1sgnlv5k0257k566s2jhd9-nixos-system-machine-test machine # [ 42.898384] systemd[1]: Stopped target Local File Systems. machine # [ 42.900317] systemd[1]: Stopped target Remote File Systems. machine # [ 42.901575] systemd[1]: required-service.service: Deactivated successfully. machine # [ 42.903218] systemd[1]: Stopped required-service.service. machine # [ 42.903930] systemd[1]: test-service.service: Deactivated successfully. machine # [ 42.905510] systemd[1]: Stopped test-service.service. machine # [ 43.130564] systemd[1]: Reload requested from client PID 3601 ('.switch-to-conf') (unit backdoor.service)... machine # [ 43.132177] systemd[1]: Reloading... machine # [ 43.392558] systemd[1]: Reloading finished in 259 ms. machine # [ 43.454294] systemd[619]: Reexecution requested from client PID 3670 ('switch-to-confi')... machine # [ 43.455585] systemd[619]: Reexecuting. machine # [ 43.476984] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.551663] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.556770] systemd[619]: Reload requested from client PID 3670 ('switch-to-confi')... machine # [ 43.558200] systemd[619]: Reloading... machine # [ 43.629495] systemd[619]: Reloading finished in 70 ms. machine # [ 43.634829] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.640909] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 43.641932] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 43.644601] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 43.664314] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 43.667295] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 43.668563] systemd[1]: Stopping Reactivate sysinit units... machine # [ 43.669567] systemd[1]: Reached target Reactivate sysinit units. machine # [ 43.675517] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.677255] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 43.679864] systemd[1]: Reached target Remote File Systems. machine # [ 43.683124] systemd[1]: Starting linger-users.service... machine # [ 43.687459] systemd[1]: Starting required-service.service... machine # [ 43.691618] systemd[1]: Starting test-service.service... machine # [ 43.700774] systemd[1]: Reached target Local File Systems. machine # [ 43.702830] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 43.709208] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 43.709969] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 43.712113] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 43.714098] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 43.715547] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.716658] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.751427] systemd[1]: Finished required-service.service. machine # [ 43.753604] systemd[1]: Finished test-service.service. machine # [ 43.777328] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 43.779172] systemd[1]: Finished linger-users.service. machine # [ 43.888931] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 43.891606] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 44.148834] nixos[3601]: finished switching to system configuration /nix/store/7acmw3fpvi1sgnlv5k0257k566s2jhd9-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/unitWithRequirementModified/bin/switch-to-configuration test 2>&1, in 1.49 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/unitWithRequirementModifiedNostart/bin/switch-to-configuration test 2>&1 machine # [ 44.395422] nixos[3745]: switching to system configuration /nix/store/jl54syf9cdh756aabz25nh9by5g03scs-nixos-system-machine-test machine # [ 44.399209] systemd[1]: Stopped target Remote File Systems. machine # [ 44.400828] systemd[1]: required-service.service: Deactivated successfully. machine # [ 44.402541] systemd[1]: Stopped required-service.service. machine # [ 44.403568] systemd[1]: test-service.service: Deactivated successfully. machine # [ 44.404672] systemd[1]: Stopped test-service.service. machine # [ 44.405611] systemd[1]: Stopped target Local File Systems. machine # [ 44.635788] systemd[1]: Reload requested from client PID 3745 ('.switch-to-conf') (unit backdoor.service)... machine # [ 44.637413] systemd[1]: Reloading... machine # [ 45.008767] systemd[1]: Reloading finished in 370 ms. machine # [ 45.077738] systemd[619]: Reexecution requested from client PID 3814 ('switch-to-confi')... machine # [ 45.080292] systemd[619]: Reexecuting. machine # [ 45.101080] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.176341] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.180921] systemd[619]: Reload requested from client PID 3814 ('switch-to-confi')... machine # [ 45.183094] systemd[619]: Reloading... machine # [ 45.258058] systemd[619]: Reloading finished in 73 ms. machine # [ 45.261866] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.267953] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 45.269880] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 45.271488] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 45.290619] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 45.293445] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 45.294860] systemd[1]: Stopping Reactivate sysinit units... machine # [ 45.295745] systemd[1]: Reached target Reactivate sysinit units. machine # [ 45.300140] systemd[1]: Reached target Remote File Systems. machine # [ 45.302106] systemd[1]: Starting linger-users.service... machine # [ 45.306205] systemd[1]: Starting required-service.service... machine # [ 45.308359] systemd[1]: Starting test-service.service... machine # [ 45.318077] systemd[1]: Reached target Local File Systems. machine # [ 45.318870] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 45.322078] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 45.322810] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 45.326038] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 45.328103] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 45.329553] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 45.334187] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 45.336401] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 45.339303] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 45.367182] systemd[1]: Finished test-service.service. machine # [ 45.369852] systemd[1]: Finished required-service.service. machine # [ 45.389160] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 45.391373] systemd[1]: Finished linger-users.service. machine # [ 45.499173] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 45.500767] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 45.760383] nixos[3745]: finished switching to system configuration /nix/store/jl54syf9cdh756aabz25nh9by5g03scs-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/unitWithRequirementModifiedNostart/bin/switch-to-configuration test 2>&1, in 1.61 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/unitWithTemplate/bin/switch-to-configuration test 2>&1 machine # [ 45.993788] nixos[3889]: switching to system configuration /nix/store/ml173335gb5ll5plpdlqaz2cc2k5c4bs-nixos-system-machine-test machine # [ 45.996201] systemd[1]: Stopped target Remote File Systems. machine # [ 45.997973] systemd[1]: test-service.service: Deactivated successfully. machine # [ 45.998856] systemd[1]: Stopped test-service.service. machine # [ 46.000632] systemd[1]: Stopped target Local File Systems. machine # [ 46.001773] systemd[1]: required-service.service: Deactivated successfully. machine # [ 46.003185] systemd[1]: Stopped required-service.service. machine # [ 46.228520] systemd[1]: Reload requested from client PID 3889 ('.switch-to-conf') (unit backdoor.service)... machine # [ 46.230191] systemd[1]: Reloading... machine # [ 46.502986] systemd[1]: Reloading finished in 271 ms. machine # [ 46.565270] systemd[619]: Reexecution requested from client PID 3958 ('switch-to-confi')... machine # [ 46.567692] systemd[619]: Reexecuting. machine # [ 46.588377] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.660900] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.666041] systemd[619]: Reload requested from client PID 3958 ('switch-to-confi')... machine # [ 46.667447] systemd[619]: Reloading... machine # [ 46.743371] systemd[619]: Reloading finished in 74 ms. machine # [ 46.748854] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.756112] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 46.757102] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 46.759198] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 46.778189] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 46.781105] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 46.782648] systemd[1]: Stopping Reactivate sysinit units... machine # [ 46.783651] systemd[1]: Reached target Reactivate sysinit units. machine # [ 46.787469] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.789108] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 46.792508] systemd[1]: Reached target Remote File Systems. machine # [ 46.795797] systemd[1]: Created slice Slice /system/instantiated. machine # [ 46.799172] systemd[1]: Starting instantiated@one.service... machine # [ 46.801145] systemd[1]: Starting instantiated@two.service... machine # [ 46.806374] systemd[1]: Starting linger-users.service... machine # [ 46.812748] systemd[1]: Reached target Local File Systems. machine # [ 46.813591] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 46.818115] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 46.819209] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 46.821133] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 46.823064] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 46.824506] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.825675] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.858379] systemd[1]: Finished instantiated@two.service. machine # [ 46.860832] systemd[1]: Finished instantiated@one.service. machine # [ 46.883306] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 46.884979] systemd[1]: Finished linger-users.service. machine # [ 46.991262] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 46.993535] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 47.252479] nixos[3889]: finished switching to system configuration /nix/store/ml173335gb5ll5plpdlqaz2cc2k5c4bs-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/unitWithTemplate/bin/switch-to-configuration test 2>&1, in 1.49 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/unitWithTemplateModified/bin/switch-to-configuration test 2>&1 machine # [ 47.504491] nixos[4033]: switching to system configuration /nix/store/wbn241hb3lc6zjkqcl3r4zyi7n9njw19-nixos-system-machine-test machine # [ 47.507030] systemd[1]: Stopped target Remote File Systems. machine # [ 47.508516] systemd[1]: Stopped target Local File Systems. machine # [ 47.510070] systemd[1]: instantiated@one.service: Deactivated successfully. machine # [ 47.511651] systemd[1]: Stopped instantiated@one.service. machine # [ 47.513099] systemd[1]: instantiated@two.service: Deactivated successfully. machine # [ 47.514029] systemd[1]: Stopped instantiated@two.service. machine # [ 47.738293] systemd[1]: Reload requested from client PID 4033 ('.switch-to-conf') (unit backdoor.service)... machine # [ 47.739937] systemd[1]: Reloading... machine # [ 48.283966] systemd[1]: Reloading finished in 543 ms. machine # [ 48.346351] systemd[619]: Reexecution requested from client PID 4102 ('switch-to-confi')... machine # [ 48.347633] systemd[619]: Reexecuting. machine # [ 48.368228] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.439848] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.444954] systemd[619]: Reload requested from client PID 4102 ('switch-to-confi')... machine # [ 48.446527] systemd[619]: Reloading... machine # [ 48.517970] systemd[619]: Reloading finished in 70 ms. machine # [ 48.523833] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.529939] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 48.531608] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 48.533985] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 48.553960] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 48.557668] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 48.558749] systemd[1]: Stopping Reactivate sysinit units... machine # [ 48.559827] systemd[1]: Reached target Reactivate sysinit units. machine # [ 48.562777] systemd[1]: Starting instantiated@one.service... machine # [ 48.569847] systemd[1]: Starting instantiated@two.service... machine # [ 48.574201] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.575454] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 48.578784] systemd[1]: Reached target Local File Systems. machine # [ 48.582482] systemd[1]: Reached target Remote File Systems. machine # [ 48.585066] systemd[1]: Starting linger-users.service... machine # [ 48.591497] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 48.595794] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 48.596630] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 48.597768] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 48.603675] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 48.605357] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.607381] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.625279] systemd[1]: Finished instantiated@one.service. machine # [ 48.633952] systemd[1]: Finished instantiated@two.service. machine # [ 48.659416] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 48.660907] systemd[1]: Finished linger-users.service. machine # [ 48.767710] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 48.769647] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 49.027367] nixos[4033]: finished switching to system configuration /nix/store/wbn241hb3lc6zjkqcl3r4zyi7n9njw19-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/unitWithTemplateModified/bin/switch-to-configuration test 2>&1, in 1.77 seconds) (finished: subtest: services, in 31.22 seconds) subtest: failing units machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1 machine # [ 49.235321] nixos[4177]: switching to system configuration /nix/store/3vwhbi9l100rcjd6f1xr90q0n4pjm127-nixos-system-machine-test machine # [ 49.237781] systemd[1]: instantiated@one.service: Deactivated successfully. machine # [ 49.239725] systemd[1]: Stopped instantiated@one.service. machine # [ 49.240585] systemd[1]: Stopped target Local File Systems. machine # [ 49.242235] systemd[1]: instantiated@two.service: Deactivated successfully. machine # [ 49.243279] systemd[1]: Stopped instantiated@two.service. machine # [ 49.244382] systemd[1]: Stopped target Remote File Systems. machine # [ 49.452892] systemd[1]: Reload requested from client PID 4177 ('.switch-to-conf') (unit backdoor.service)... machine # [ 49.454553] systemd[1]: Reloading... machine # [ 49.670845] systemd[1]: Reloading finished in 215 ms. machine # [ 49.733352] systemd[619]: Reexecution requested from client PID 4246 ('switch-to-confi')... machine # [ 49.735865] systemd[619]: Reexecuting. machine # [ 49.757054] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.830807] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.835883] systemd[619]: Reload requested from client PID 4246 ('switch-to-confi')... machine # [ 49.837876] systemd[619]: Reloading... machine # [ 49.914281] systemd[619]: Reloading finished in 75 ms. machine # [ 49.919839] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.926156] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 49.927225] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 49.929553] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 49.947857] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 49.950842] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 49.951922] systemd[1]: Stopping Reactivate sysinit units... machine # [ 49.953206] systemd[1]: Reached target Reactivate sysinit units. machine # [ 49.957627] systemd[1]: Reached target Remote File Systems. machine # [ 49.959665] systemd[1]: Reached target Local File Systems. machine # [ 49.962851] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 49.964582] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 49.967334] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 49.971200] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 49.971977] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 49.974219] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 49.976147] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 49.977602] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 49.978745] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 50.103919] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 50.107151] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 50.110064] systemd[1]: Starting linger-users.service... machine # [ 50.112054] systemd[1]: Starting test.service... machine # [ 50.135980] systemd[1]: Finished test.service. machine # [ 50.148822] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 50.150247] systemd[1]: Finished linger-users.service. machine # [ 50.407948] nixos[4177]: finished switching to system configuration /nix/store/3vwhbi9l100rcjd6f1xr90q0n4pjm127-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1, in 1.38 seconds) machine: must fail: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceFailing/bin/switch-to-configuration test 2>&1 machine # [ 50.645726] nixos[4319]: switching to system configuration /nix/store/wdridbhh7ksv565f8lmljap2ivin5s70-nixos-system-machine-test machine # [ 50.647909] systemd[1]: Stopped target Remote File Systems. machine # [ 50.649780] systemd[1]: test.service: Deactivated successfully. machine # [ 50.651097] systemd[1]: Stopped test.service. machine # [ 50.652192] systemd[1]: Stopped target Local File Systems. machine # [ 50.880488] systemd[1]: Reload requested from client PID 4319 ('.switch-to-conf') (unit backdoor.service)... machine # [ 50.882349] systemd[1]: Reloading... machine # [ 51.116055] systemd[1]: Reloading finished in 231 ms. machine # [ 51.175842] systemd[619]: Reexecution requested from client PID 4388 ('switch-to-confi')... machine # [ 51.177860] systemd[619]: Reexecuting. machine # [ 51.198042] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.273342] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.278348] systemd[619]: Reload requested from client PID 4388 ('switch-to-confi')... machine # [ 51.280616] systemd[619]: Reloading... machine # [ 51.350810] systemd[619]: Reloading finished in 70 ms. machine # [ 51.356832] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.365264] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 51.367055] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 51.368807] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 51.389634] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 51.392677] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 51.393968] systemd[1]: Stopping Reactivate sysinit units... machine # [ 51.395152] systemd[1]: Reached target Reactivate sysinit units. machine # [ 51.398522] systemd[1]: Reached target Remote File Systems. machine # [ 51.401175] systemd[1]: Starting linger-users.service... machine # [ 51.404133] systemd[1]: Starting test.service... machine # [ 51.407479] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.408701] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 51.418197] systemd[1]: Reached target Local File Systems. machine # [ 51.418947] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 51.422519] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 51.423295] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 51.424396] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 51.426322] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 51.427711] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.428856] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.456685] systemd[1]: test.service: Main process exited, code=exited, status=1/FAILURE machine # [ 51.457900] systemd[1]: test.service: Failed with result 'exit-code'. machine # [ 51.459486] systemd[1]: Failed to start test.service. machine # [ 51.480925] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 51.482656] systemd[1]: Finished linger-users.service. machine # [ 51.588125] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 51.590888] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 51.869500] nixos[4319]: switching to system configuration /nix/store/wdridbhh7ksv565f8lmljap2ivin5s70-nixos-system-machine-test failed (status 4) machine: (finished: must fail: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceFailing/bin/switch-to-configuration test 2>&1, in 1.46 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1 machine # [ 52.082093] nixos[4462]: switching to system configuration /nix/store/y3iv8rpcaf7dzryjvn4wrz9jl7jhn9pl-nixos-system-machine-test machine # [ 52.084382] systemd[1]: Stopped target Local File Systems. machine # [ 52.085962] systemd[1]: Stopped target Remote File Systems. machine # [ 52.308651] systemd[1]: Reload requested from client PID 4462 ('.switch-to-conf') (unit backdoor.service)... machine # [ 52.310366] systemd[1]: Reloading... machine # [ 52.549090] systemd[1]: Reloading finished in 237 ms. machine # [ 52.610993] systemd[619]: Reexecution requested from client PID 4531 ('switch-to-confi')... machine # [ 52.612821] systemd[619]: Reexecuting. machine # [ 52.633078] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.704954] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.710771] systemd[619]: Reload requested from client PID 4531 ('switch-to-confi')... machine # [ 52.712349] systemd[619]: Reloading... machine # [ 52.784407] systemd[619]: Reloading finished in 71 ms. machine # [ 52.789841] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.796037] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 52.796974] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 52.800073] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 52.818966] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 52.821902] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 52.823240] systemd[1]: Stopping Reactivate sysinit units... machine # [ 52.823970] systemd[1]: Reached target Reactivate sysinit units. machine # [ 52.826944] systemd[1]: Reached target Remote File Systems. machine # [ 52.829659] systemd[1]: Started autorestart.service. machine # [ 52.834093] systemd[1]: Starting linger-users.service... machine # [ 52.837147] systemd[1]: Reached target Local File Systems. machine # [ 52.843515] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 52.847878] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 52.848720] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 52.849892] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 52.851862] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 52.853269] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 52.854414] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 52.862840] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 52.865305] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 52.884098] systemd[1]: autorestart.service: Deactivated successfully. machine # [ 52.906774] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 52.908967] systemd[1]: Finished linger-users.service. machine # [ 53.018068] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 53.019831] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 53.278235] nixos[4462]: finished switching to system configuration /nix/store/y3iv8rpcaf7dzryjvn4wrz9jl7jhn9pl-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1, in 1.41 seconds) machine # [ 53.324434] systemd[1]: Stopped autorestart.service. machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1 machine # [ 53.516592] nixos[4609]: switching to system configuration /nix/store/y3iv8rpcaf7dzryjvn4wrz9jl7jhn9pl-nixos-system-machine-test machine # [ 53.519845] systemd[1]: Stopped target Local File Systems. machine # [ 53.520919] systemd[1]: Stopped target Remote File Systems. machine # [ 53.722245] systemd[1]: Reload requested from client PID 4609 ('.switch-to-conf') (unit backdoor.service)... machine # [ 53.723861] systemd[1]: Reloading... machine # [ 53.953539] systemd[1]: Reloading finished in 228 ms. machine # [ 54.016113] systemd[619]: Reexecution requested from client PID 4678 ('switch-to-confi')... machine # [ 54.017911] systemd[619]: Reexecuting. machine # [ 54.038117] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.111772] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.116720] systemd[619]: Reload requested from client PID 4678 ('switch-to-confi')... machine # [ 54.118060] systemd[619]: Reloading... machine # [ 54.192973] systemd[619]: Reloading finished in 73 ms. machine # [ 54.198830] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.205059] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 54.206917] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 54.208467] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 54.227138] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 54.230079] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 54.231718] systemd[1]: Stopping Reactivate sysinit units... machine # [ 54.232848] systemd[1]: Reached target Reactivate sysinit units. machine # [ 54.238143] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 54.239858] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 54.242533] systemd[1]: Reached target Remote File Systems. machine # [ 54.245211] systemd[1]: Started autorestart.service. machine # [ 54.248369] systemd[1]: Starting linger-users.service... machine # [ 54.256069] systemd[1]: Reached target Local File Systems. machine # [ 54.258165] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 54.262463] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 54.263291] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 54.264450] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 54.268290] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 54.271144] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 54.273806] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 54.292461] systemd[1]: autorestart.service: Deactivated successfully. machine # [ 54.314632] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 54.316831] systemd[1]: Finished linger-users.service. machine # [ 54.424867] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 54.426545] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 54.684547] nixos[4609]: finished switching to system configuration /nix/store/y3iv8rpcaf7dzryjvn4wrz9jl7jhn9pl-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1, in 1.36 seconds) machine # [ 54.730738] systemd[1]: Stopped autorestart.service. machine: must fail: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/autorestartServiceFailing/bin/switch-to-configuration test 2>&1 machine # [ 54.932547] nixos[4756]: switching to system configuration /nix/store/m52nrkbfxbdz3wklsw9fl51k0adv8k7f-nixos-system-machine-test machine # [ 54.935886] systemd[1]: Stopped target Local File Systems. machine # [ 54.936744] systemd[1]: Stopped target Remote File Systems. machine # [ 55.158627] systemd[1]: Reload requested from client PID 4756 ('.switch-to-conf') (unit backdoor.service)... machine # [ 55.160332] systemd[1]: Reloading... machine # [ 55.397245] systemd[1]: Reloading finished in 235 ms. machine # [ 55.458315] systemd[619]: Reexecution requested from client PID 4825 ('switch-to-confi')... machine # [ 55.460706] systemd[619]: Reexecuting. machine # [ 55.481062] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.552228] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.557448] systemd[619]: Reload requested from client PID 4825 ('switch-to-confi')... machine # [ 55.558646] systemd[619]: Reloading... machine # [ 55.630132] systemd[619]: Reloading finished in 69 ms. machine # [ 55.635833] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.642474] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 55.643488] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 55.646039] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 55.665126] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 55.668233] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 55.669737] systemd[1]: Stopping Reactivate sysinit units... machine # [ 55.670915] systemd[1]: Reached target Reactivate sysinit units. machine # [ 55.672713] systemd[1]: Reached target Local File Systems. machine # [ 55.674130] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 55.677747] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 55.678594] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 55.679714] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 55.681640] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 55.682982] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.684097] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.689109] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.690334] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 55.693587] systemd[1]: Reached target Remote File Systems. machine # [ 55.819963] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 55.821612] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 55.825426] systemd[1]: Started autorestart.service. machine # [ 55.827840] systemd[1]: Starting linger-users.service... machine # [ 55.850910] systemd[1]: autorestart.service: Main process exited, code=exited, status=1/FAILURE machine # [ 55.852726] systemd[1]: autorestart.service: Failed with result 'exit-code'. machine # [ 55.866964] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 55.869245] systemd[1]: Finished linger-users.service. machine # [ 56.148972] nixos[4756]: switching to system configuration /nix/store/m52nrkbfxbdz3wklsw9fl51k0adv8k7f-nixos-system-machine-test failed (status 4) machine: (finished: must fail: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/autorestartServiceFailing/bin/switch-to-configuration test 2>&1, in 1.42 seconds) (finished: subtest: failing units, in 7.12 seconds) subtest: unit file parser machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 56.344070] nixos[4899]: switching to system configuration /nix/store/58qnsk9s7jd99bn1nr3fgl7i5v3xfsvr-nixos-system-machine-test machine # [ 56.346392] systemd[1]: Stopped target Local File Systems. machine # [ 56.348250] systemd[1]: Stopped autorestart.service. machine # [ 56.349594] systemd[1]: Stopped target Remote File Systems. machine # [ 56.551986] systemd[1]: Reload requested from client PID 4899 ('.switch-to-conf') (unit backdoor.service)... machine # [ 56.553703] systemd[1]: Reloading... machine # [ 56.785395] systemd[1]: Reloading finished in 230 ms. machine # [ 56.849419] systemd[619]: Reexecution requested from client PID 4968 ('switch-to-confi')... machine # [ 56.852027] systemd[619]: Reexecuting. machine # [ 56.873237] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 56.947364] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 56.952407] systemd[619]: Reload requested from client PID 4968 ('switch-to-confi')... machine # [ 56.954643] systemd[619]: Reloading... machine # [ 57.029093] systemd[619]: Reloading finished in 74 ms. machine # [ 57.032830] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.038767] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 57.039754] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 57.042194] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 57.061872] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 57.064864] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 57.066166] systemd[1]: Stopping Reactivate sysinit units... machine # [ 57.066897] systemd[1]: Reached target Reactivate sysinit units. machine # [ 57.071977] systemd[1]: Reached target Remote File Systems. machine # [ 57.074142] systemd[1]: Starting linger-users.service... machine # [ 57.075833] systemd[1]: Starting test.service... machine # [ 57.082832] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 57.084107] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 57.090350] systemd[1]: Reached target Local File Systems. machine # [ 57.091203] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 57.095840] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 57.096681] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 57.097813] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 57.099764] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 57.101179] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 57.102318] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 57.126446] systemd[1]: Finished test.service. machine # [ 57.146988] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 57.148735] systemd[1]: Finished linger-users.service. machine # [ 57.256542] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 57.259414] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 57.516133] nixos[4899]: finished switching to system configuration /nix/store/58qnsk9s7jd99bn1nr3fgl7i5v3xfsvr-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 1.37 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceWithExtraSection/bin/switch-to-configuration test 2>&1 machine # [ 57.748495] nixos[5041]: switching to system configuration /nix/store/44m22szbzfqlvgvh2sckw205h004flr0-nixos-system-machine-test machine # [ 57.750751] systemd[1]: Stopped target Remote File Systems. machine # [ 57.752521] systemd[1]: Stopped target Local File Systems. machine # [ 57.973993] systemd[1]: Reload requested from client PID 5041 ('.switch-to-conf') (unit backdoor.service)... machine # [ 57.975829] systemd[1]: Reloading... machine # [ 58.230829] systemd[1]: Reloading finished in 253 ms. machine # [ 58.298518] systemd[619]: Reexecution requested from client PID 5110 ('switch-to-confi')... machine # [ 58.301080] systemd[619]: Reexecuting. machine # [ 58.321191] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 58.395199] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 58.400421] systemd[619]: Reload requested from client PID 5110 ('switch-to-confi')... machine # [ 58.401582] systemd[619]: Reloading... machine # [ 58.472497] systemd[619]: Reloading finished in 69 ms. machine # [ 58.477872] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 58.484215] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 58.485968] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 58.487453] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 58.506398] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 58.509420] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 58.510642] systemd[1]: Stopping Reactivate sysinit units... machine # [ 58.511694] systemd[1]: Reached target Reactivate sysinit units. machine # [ 58.513440] systemd[1]: test.service: Deactivated successfully. machine # [ 58.515126] systemd[1]: Stopped test.service. machine # [ 58.515743] systemd[1]: Stopping test.service... machine # [ 58.517661] systemd[1]: Starting test.service... machine # [ 58.531601] systemd[1]: Finished test.service. machine # [ 58.534160] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 58.535647] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 58.539236] systemd[1]: Reached target Local File Systems. machine # [ 58.541184] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 58.544295] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 58.545927] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 58.548158] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 58.552151] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 58.553555] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 58.555238] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 58.560394] systemd[1]: Reached target Remote File Systems. machine # [ 58.683747] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 58.687121] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 58.689066] systemd[1]: Starting linger-users.service... machine # [ 58.716506] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 58.718071] systemd[1]: Finished linger-users.service. machine # [ 58.975251] nixos[5041]: finished switching to system configuration /nix/store/44m22szbzfqlvgvh2sckw205h004flr0-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceWithExtraSection/bin/switch-to-configuration test 2>&1, in 1.46 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceWithExtraSectionOtherName/bin/switch-to-configuration test 2>&1 machine # [ 59.191416] nixos[5183]: switching to system configuration /nix/store/hzxg3q76g01c83w3kb1y3y96bsqdqd67-nixos-system-machine-test machine # [ 59.193618] systemd[1]: Stopped target Local File Systems. machine # [ 59.195327] systemd[1]: Stopped target Remote File Systems. machine # [ 59.421809] systemd[1]: Reload requested from client PID 5183 ('.switch-to-conf') (unit backdoor.service)... machine # [ 59.423421] systemd[1]: Reloading... machine # [ 59.667711] systemd[1]: Reloading finished in 242 ms. machine # [ 59.733861] systemd[619]: Reexecution requested from client PID 5252 ('switch-to-confi')... machine # [ 59.736464] systemd[619]: Reexecuting. machine # [ 59.757355] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.834281] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.839696] systemd[619]: Reload requested from client PID 5252 ('switch-to-confi')... machine # [ 59.840894] systemd[619]: Reloading... machine # [ 59.912936] systemd[619]: Reloading finished in 71 ms. machine # [ 59.917834] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.924359] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 59.925198] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 59.927258] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 59.948396] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 59.951369] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 59.952778] systemd[1]: Stopping Reactivate sysinit units... machine # [ 59.953821] systemd[1]: Reached target Reactivate sysinit units. machine # [ 59.955480] systemd[1]: test.service: Deactivated successfully. machine # [ 59.957276] systemd[1]: Stopped test.service. machine # [ 59.957916] systemd[1]: Stopping test.service... machine # [ 59.959904] systemd[1]: Starting test.service... machine # [ 59.974363] systemd[1]: Finished test.service. machine # [ 59.979062] systemd[1]: Reached target Remote File Systems. machine # [ 59.981994] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 59.983812] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 59.988572] systemd[1]: Starting linger-users.service... machine # [ 59.991126] systemd[1]: Reached target Local File Systems. machine # [ 59.991935] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 59.996060] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 59.996830] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 59.997968] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 59.999913] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 60.001336] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 60.005120] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 60.046339] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 60.047791] systemd[1]: Finished linger-users.service. machine # [ 60.151416] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 60.153068] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 60.410799] nixos[5183]: finished switching to system configuration /nix/store/hzxg3q76g01c83w3kb1y3y96bsqdqd67-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceWithExtraSectionOtherName/bin/switch-to-configuration test 2>&1, in 1.44 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 60.624280] nixos[5325]: switching to system configuration /nix/store/58qnsk9s7jd99bn1nr3fgl7i5v3xfsvr-nixos-system-machine-test machine # [ 60.626581] systemd[1]: Stopped target Remote File Systems. machine # [ 60.628489] systemd[1]: Stopped target Local File Systems. machine # [ 60.837844] systemd[1]: Reload requested from client PID 5325 ('.switch-to-conf') (unit backdoor.service)... machine # [ 60.839458] systemd[1]: Reloading... machine # [ 61.068130] systemd[1]: Reloading finished in 227 ms. machine # [ 61.129768] systemd[619]: Reexecution requested from client PID 5394 ('switch-to-confi')... machine # [ 61.131576] systemd[619]: Reexecuting. machine # [ 61.151175] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 61.221953] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 61.227629] systemd[619]: Reload requested from client PID 5394 ('switch-to-confi')... machine # [ 61.228811] systemd[619]: Reloading... machine # [ 61.301723] systemd[619]: Reloading finished in 71 ms. machine # [ 61.307865] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 61.313689] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 61.315610] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 61.317152] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 61.336826] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 61.339792] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 61.341252] systemd[1]: Stopping Reactivate sysinit units... machine # [ 61.342159] systemd[1]: Reached target Reactivate sysinit units. machine # [ 61.343824] systemd[1]: test.service: Deactivated successfully. machine # [ 61.345208] systemd[1]: Stopped test.service. machine # [ 61.345798] systemd[1]: Stopping test.service... machine # [ 61.347712] systemd[1]: Starting test.service... machine # [ 61.361168] systemd[1]: Finished test.service. machine # [ 61.363711] systemd[1]: Reached target Remote File Systems. machine # [ 61.366864] systemd[1]: Starting linger-users.service... machine # [ 61.369148] systemd[1]: Reached target Local File Systems. machine # [ 61.377145] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 61.380205] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 61.380941] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 61.383609] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 61.386271] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 61.388884] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 61.391839] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 61.393988] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 61.395288] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 61.429785] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 61.432148] systemd[1]: Finished linger-users.service. machine # [ 61.537931] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 61.539768] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 61.797473] nixos[5325]: finished switching to system configuration /nix/store/58qnsk9s7jd99bn1nr3fgl7i5v3xfsvr-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 1.38 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceWithInstallSection/bin/switch-to-configuration test 2>&1 machine # [ 62.029205] nixos[5467]: switching to system configuration /nix/store/blk5q561ynhl6b8syl9dk9xl2ghya30p-nixos-system-machine-test machine # [ 62.032225] systemd[1]: Stopped target Remote File Systems. machine # [ 62.032947] systemd[1]: Stopped target Local File Systems. machine # [ 62.258180] systemd[1]: Reload requested from client PID 5467 ('.switch-to-conf') (unit backdoor.service)... machine # [ 62.259839] systemd[1]: Reloading... machine # [ 62.506640] systemd[1]: Reloading finished in 245 ms. machine # [ 62.572333] systemd[619]: Reexecution requested from client PID 5536 ('switch-to-confi')... machine # [ 62.574733] systemd[619]: Reexecuting. machine # [ 62.591251] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.668906] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.673997] systemd[619]: Reload requested from client PID 5536 ('switch-to-confi')... machine # [ 62.675456] systemd[619]: Reloading... machine # [ 62.746936] systemd[619]: Reloading finished in 70 ms. machine # [ 62.752845] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.759043] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 62.760059] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 62.762591] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 62.783483] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 62.786526] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 62.787874] systemd[1]: Stopping Reactivate sysinit units... machine # [ 62.789104] systemd[1]: Reached target Reactivate sysinit units. machine # [ 62.793685] systemd[1]: Reached target Local File Systems. machine # [ 62.795458] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 62.798188] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 62.798924] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 62.801712] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 62.803943] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 62.805388] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.808139] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.812195] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.813403] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 62.816242] systemd[1]: Reached target Remote File Systems. machine # [ 62.943234] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 62.944856] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 62.948539] systemd[1]: Starting linger-users.service... machine # [ 62.975854] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 62.977358] systemd[1]: Finished linger-users.service. machine # [ 63.234496] nixos[5467]: finished switching to system configuration /nix/store/blk5q561ynhl6b8syl9dk9xl2ghya30p-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceWithInstallSection/bin/switch-to-configuration test 2>&1, in 1.44 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceWithExtraKey/bin/switch-to-configuration test 2>&1 machine # [ 63.469387] nixos[5607]: switching to system configuration /nix/store/0c35hqwpj9ry3qq2yjbr6vp5a6182isv-nixos-system-machine-test machine # [ 63.471604] systemd[1]: Stopped target Remote File Systems. machine # [ 63.473285] systemd[1]: Stopped target Local File Systems. machine # [ 63.707933] systemd[1]: Reload requested from client PID 5607 ('.switch-to-conf') (unit backdoor.service)... machine # [ 63.709587] systemd[1]: Reloading... machine # [ 63.963505] systemd[1]: Reloading finished in 252 ms. machine # [ 64.027077] systemd[619]: Reexecution requested from client PID 5676 ('switch-to-confi')... machine # [ 64.028310] systemd[619]: Reexecuting. machine # [ 64.048061] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 64.122502] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 64.128104] systemd[619]: Reload requested from client PID 5676 ('switch-to-confi')... machine # [ 64.129305] systemd[619]: Reloading... machine # [ 64.203127] systemd[619]: Reloading finished in 72 ms. machine # [ 64.208861] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 64.214861] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 64.216274] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 64.218246] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 64.237271] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 64.240247] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 64.241610] systemd[1]: Stopping Reactivate sysinit units... machine # [ 64.242728] systemd[1]: Reached target Reactivate sysinit units. machine # [ 64.244092] systemd[1]: test.service: Deactivated successfully. machine # [ 64.246173] systemd[1]: Stopped test.service. machine # [ 64.246789] systemd[1]: Stopping test.service... machine # [ 64.248176] systemd[1]: Starting test.service... machine # [ 64.261658] systemd[1]: Finished test.service. machine # [ 64.264455] systemd[1]: Reached target Local File Systems. machine # [ 64.267451] systemd[1]: Reached target Remote File Systems. machine # [ 64.269960] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 64.271557] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 64.275821] systemd[1]: Starting linger-users.service... machine # [ 64.283414] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 64.285646] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 64.287408] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 64.289180] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 64.291085] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 64.292490] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 64.293612] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 64.328540] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 64.330691] systemd[1]: Finished linger-users.service. machine # [ 64.437465] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 64.439134] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 64.694324] nixos[5607]: finished switching to system configuration /nix/store/0c35hqwpj9ry3qq2yjbr6vp5a6182isv-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceWithExtraKey/bin/switch-to-configuration test 2>&1, in 1.46 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherValue/bin/switch-to-configuration test 2>&1 machine # [ 64.889412] nixos[5749]: switching to system configuration /nix/store/21lv3slfqfjqf2r4mck85kizfrhygp1p-nixos-system-machine-test machine # [ 64.891887] systemd[1]: Stopped target Remote File Systems. machine # [ 64.893746] systemd[1]: Stopped target Local File Systems. machine # [ 65.099653] systemd[1]: Reload requested from client PID 5749 ('.switch-to-conf') (unit backdoor.service)... machine # [ 65.101359] systemd[1]: Reloading... machine # [ 65.381637] systemd[1]: Reloading finished in 279 ms. machine # [ 65.444204] systemd[619]: Reexecution requested from client PID 5818 ('switch-to-confi')... machine # [ 65.445977] systemd[619]: Reexecuting. machine # [ 65.467262] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.538636] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.544387] systemd[619]: Reload requested from client PID 5818 ('switch-to-confi')... machine # [ 65.546088] systemd[619]: Reloading... machine # [ 65.616946] systemd[619]: Reloading finished in 70 ms. machine # [ 65.621835] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.628188] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 65.629089] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 65.631447] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 65.653125] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 65.656509] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 65.657614] systemd[1]: Stopping Reactivate sysinit units... machine # [ 65.658581] systemd[1]: Reached target Reactivate sysinit units. machine # [ 65.660267] systemd[1]: test.service: Deactivated successfully. machine # [ 65.662229] systemd[1]: Stopped test.service. machine # [ 65.662840] systemd[1]: Stopping test.service... machine # [ 65.664426] systemd[1]: Starting test.service... machine # [ 65.678671] systemd[1]: Finished test.service. machine # [ 65.680620] systemd[1]: Reached target Remote File Systems. machine # [ 65.683191] systemd[1]: Reached target Local File Systems. machine # [ 65.686061] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 65.687744] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 65.691648] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 65.698100] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 65.698866] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 65.699957] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 65.701889] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 65.703341] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 65.704519] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 65.838911] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 65.840612] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 65.844686] systemd[1]: Starting linger-users.service... machine # [ 65.871861] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 65.873490] systemd[1]: Finished linger-users.service. machine # [ 66.130657] nixos[5749]: finished switching to system configuration /nix/store/21lv3slfqfjqf2r4mck85kizfrhygp1p-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherValue/bin/switch-to-configuration test 2>&1, in 1.44 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherName/bin/switch-to-configuration test 2>&1 machine # [ 66.377166] nixos[5891]: switching to system configuration /nix/store/6ixp46z264kz2bvd5x14ikbpaf6k85l6-nixos-system-machine-test machine # [ 66.380789] systemd[1]: Stopped target Local File Systems. machine # [ 66.381698] systemd[1]: Stopped target Remote File Systems. machine # [ 66.604874] systemd[1]: Reload requested from client PID 5891 ('.switch-to-conf') (unit backdoor.service)... machine # [ 66.606576] systemd[1]: Reloading... machine # [ 66.845863] systemd[1]: Reloading finished in 238 ms. machine # [ 66.912229] systemd[619]: Reexecution requested from client PID 5960 ('switch-to-confi')... machine # [ 66.913487] systemd[619]: Reexecuting. machine # [ 66.934248] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 67.006720] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 67.012093] systemd[619]: Reload requested from client PID 5960 ('switch-to-confi')... machine # [ 67.013362] systemd[619]: Reloading... machine # [ 67.087505] systemd[619]: Reloading finished in 72 ms. machine # [ 67.092847] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 67.099486] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 67.100458] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 67.103071] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 67.122493] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 67.125444] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 67.126729] systemd[1]: Stopping Reactivate sysinit units... machine # [ 67.127830] systemd[1]: Reached target Reactivate sysinit units. machine # [ 67.129460] systemd[1]: test.service: Deactivated successfully. machine # [ 67.131251] systemd[1]: Stopped test.service. machine # [ 67.131839] systemd[1]: Stopping test.service... machine # [ 67.133456] systemd[1]: Starting test.service... machine # [ 67.148387] systemd[1]: Finished test.service. machine # [ 67.151683] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 67.153455] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 67.156205] systemd[1]: Reached target Local File Systems. machine # [ 67.160748] systemd[1]: Reached target Remote File Systems. machine # [ 67.164954] systemd[1]: Starting linger-users.service... machine # [ 67.167172] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 67.172083] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 67.172863] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 67.173981] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 67.175892] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 67.177286] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 67.181090] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 67.221123] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 67.222325] systemd[1]: Finished linger-users.service. machine # [ 67.324986] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 67.326824] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 67.584879] nixos[5891]: finished switching to system configuration /nix/store/6ixp46z264kz2bvd5x14ikbpaf6k85l6-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherName/bin/switch-to-configuration test 2>&1, in 1.45 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 67.796554] nixos[6033]: switching to system configuration /nix/store/58qnsk9s7jd99bn1nr3fgl7i5v3xfsvr-nixos-system-machine-test machine # [ 67.798568] systemd[1]: Stopped target Remote File Systems. machine # [ 67.799356] systemd[1]: Stopped target Local File Systems. machine # [ 68.002305] systemd[1]: Reload requested from client PID 6033 ('.switch-to-conf') (unit backdoor.service)... machine # [ 68.003958] systemd[1]: Reloading... machine # [ 68.232633] systemd[1]: Reloading finished in 226 ms. machine # [ 68.291948] systemd[619]: Reexecution requested from client PID 6102 ('switch-to-confi')... machine # [ 68.294205] systemd[619]: Reexecuting. machine # [ 68.315076] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.387386] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.392517] systemd[619]: Reload requested from client PID 6102 ('switch-to-confi')... machine # [ 68.393678] systemd[619]: Reloading... machine # [ 68.463992] systemd[619]: Reloading finished in 69 ms. machine # [ 68.469839] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.475854] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 68.477300] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 68.479178] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 68.497877] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 68.500808] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 68.502616] systemd[1]: Stopping Reactivate sysinit units... machine # [ 68.503685] systemd[1]: Reached target Reactivate sysinit units. machine # [ 68.505205] systemd[1]: test.service: Deactivated successfully. machine # [ 68.507191] systemd[1]: Stopped test.service. machine # [ 68.507793] systemd[1]: Stopping test.service... machine # [ 68.509164] systemd[1]: Starting test.service... machine # [ 68.522194] systemd[1]: Finished test.service. machine # [ 68.526401] systemd[1]: Reached target Remote File Systems. machine # [ 68.529689] systemd[1]: Starting linger-users.service... machine # [ 68.533238] systemd[1]: Reached target Local File Systems. machine # [ 68.535280] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 68.540248] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 68.542239] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 68.544153] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 68.547138] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 68.549279] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 68.553423] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 68.554795] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 68.556180] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 68.591970] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 68.593943] systemd[1]: Finished linger-users.service. machine # [ 68.701206] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 68.702942] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 68.959480] nixos[6033]: finished switching to system configuration /nix/store/58qnsk9s7jd99bn1nr3fgl7i5v3xfsvr-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 1.38 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceReloadTrigger/bin/switch-to-configuration test 2>&1 machine # [ 69.189175] nixos[6175]: switching to system configuration /nix/store/425b3bkyvdyg75rx2cisspyqazfpd0dg-nixos-system-machine-test machine # [ 69.192594] systemd[1]: Stopped target Remote File Systems. machine # [ 69.193570] systemd[1]: Stopped target Local File Systems. machine # [ 69.415814] systemd[1]: Reload requested from client PID 6175 ('.switch-to-conf') (unit backdoor.service)... machine # [ 69.417569] systemd[1]: Reloading... machine # [ 69.696856] systemd[1]: Reloading finished in 277 ms. machine # [ 69.758709] systemd[619]: Reexecution requested from client PID 6244 ('switch-to-confi')... machine # [ 69.760506] systemd[619]: Reexecuting. machine # [ 69.780047] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.856455] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.861787] systemd[619]: Reload requested from client PID 6244 ('switch-to-confi')... machine # [ 69.863881] systemd[619]: Reloading... machine # [ 69.937299] systemd[619]: Reloading finished in 73 ms. machine # [ 69.942852] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.948901] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 69.950805] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 69.952257] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 69.971371] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 69.974400] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 69.975696] systemd[1]: Stopping Reactivate sysinit units... machine # [ 69.976610] systemd[1]: Reached target Reactivate sysinit units. machine # [ 69.979687] systemd[1]: Reloading test.service... machine # [ 69.993808] systemd[1]: Reloaded test.service. machine # [ 69.995852] systemd[1]: Reached target Remote File Systems. machine # [ 69.999094] systemd[1]: Reached target Local File Systems. machine # [ 70.001289] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 70.003230] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 70.005482] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 70.006740] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 70.009467] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 70.013130] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 70.014378] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 70.019841] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 70.021173] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 70.150282] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 70.151879] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 70.156062] systemd[1]: Starting linger-users.service... machine # [ 70.185241] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 70.187259] systemd[1]: Finished linger-users.service. machine # [ 70.443869] nixos[6175]: finished switching to system configuration /nix/store/425b3bkyvdyg75rx2cisspyqazfpd0dg-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 1.48 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModified/bin/switch-to-configuration test 2>&1 machine # [ 70.685412] nixos[6317]: switching to system configuration /nix/store/hz21lflmlr72b63ja00q6r94zzpsflvx-nixos-system-machine-test machine # [ 70.687887] systemd[1]: Stopped target Local File Systems. machine # [ 70.689775] systemd[1]: Stopped target Remote File Systems. machine # [ 70.918916] systemd[1]: Reload requested from client PID 6317 ('.switch-to-conf') (unit backdoor.service)... machine # [ 70.920611] systemd[1]: Reloading... machine # [ 71.163820] systemd[1]: Reloading finished in 241 ms. machine # [ 71.227886] systemd[619]: Reexecution requested from client PID 6386 ('switch-to-confi')... machine # [ 71.229851] systemd[619]: Reexecuting. machine # [ 71.253194] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 71.327089] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 71.332733] systemd[619]: Reload requested from client PID 6386 ('switch-to-confi')... machine # [ 71.334421] systemd[619]: Reloading... machine # [ 71.407531] systemd[619]: Reloading finished in 71 ms. machine # [ 71.411837] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 71.417817] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 71.418849] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 71.421187] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 71.439994] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 71.442952] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 71.444216] systemd[1]: Stopping Reactivate sysinit units... machine # [ 71.444946] systemd[1]: Reached target Reactivate sysinit units. machine # [ 71.448582] systemd[1]: Reloading test.service... machine # [ 71.462204] systemd[1]: Reloaded test.service. machine # [ 71.466444] systemd[1]: Reached target Local File Systems. machine # [ 71.468383] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 71.470415] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 71.472144] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 71.474440] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 71.477142] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 71.480116] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 71.481362] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 71.484908] systemd[1]: Reached target Remote File Systems. machine # [ 71.488857] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 71.490477] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 71.614841] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 71.616690] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 71.620697] systemd[1]: Starting linger-users.service... machine # [ 71.646874] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 71.649121] systemd[1]: Finished linger-users.service. machine # [ 71.906100] nixos[6317]: finished switching to system configuration /nix/store/hz21lflmlr72b63ja00q6r94zzpsflvx-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModified/bin/switch-to-configuration test 2>&1, in 1.46 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedAndSomethingElse/bin/switch-to-configuration test 2>&1 machine # [ 72.145864] nixos[6459]: switching to system configuration /nix/store/kpra1f63cqg7j8rr68yh56hlc5h0z2p9-nixos-system-machine-test machine # [ 72.149630] systemd[1]: Stopped target Remote File Systems. machine # [ 72.150714] systemd[1]: Stopped target Local File Systems. machine # [ 72.374455] systemd[1]: Reload requested from client PID 6459 ('.switch-to-conf') (unit backdoor.service)... machine # [ 72.376184] systemd[1]: Reloading... machine # [ 72.620685] systemd[1]: Reloading finished in 243 ms. machine # [ 72.683364] systemd[619]: Reexecution requested from client PID 6528 ('switch-to-confi')... machine # [ 72.685960] systemd[619]: Reexecuting. machine # [ 72.707066] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 72.779709] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 72.785028] systemd[619]: Reload requested from client PID 6528 ('switch-to-confi')... machine # [ 72.786444] systemd[619]: Reloading... machine # [ 72.858850] systemd[619]: Reloading finished in 70 ms. machine # [ 72.864824] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 72.871796] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 72.872854] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 72.874643] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 72.893941] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 72.897553] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 72.898865] systemd[1]: Stopping Reactivate sysinit units... machine # [ 72.900194] systemd[1]: Reached target Reactivate sysinit units. machine # [ 72.901670] systemd[1]: test.service: Deactivated successfully. machine # [ 72.903712] systemd[1]: Stopped test.service. machine # [ 72.904356] systemd[1]: Stopping test.service... machine # [ 72.905789] systemd[1]: Starting test.service... machine # [ 72.919178] systemd[1]: Finished test.service. machine # [ 72.921312] systemd[1]: Reached target Remote File Systems. machine # [ 72.923857] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 72.925596] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 72.929497] systemd[1]: Reached target Local File Systems. machine # [ 72.931904] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 72.934842] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 72.935749] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 72.937089] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 72.939317] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 72.943163] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 72.946166] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 73.074789] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 73.078182] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 73.080576] systemd[1]: Starting linger-users.service... machine # [ 73.107572] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 73.109197] systemd[1]: Finished linger-users.service. machine # [ 73.365663] nixos[6459]: finished switching to system configuration /nix/store/kpra1f63cqg7j8rr68yh56hlc5h0z2p9-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedAndSomethingElse/bin/switch-to-configuration test 2>&1, in 1.46 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedSomethingElse/bin/switch-to-configuration test 2>&1 machine # [ 73.557958] nixos[6601]: switching to system configuration /nix/store/0c35hqwpj9ry3qq2yjbr6vp5a6182isv-nixos-system-machine-test machine # [ 73.560838] systemd[1]: Stopped target Remote File Systems. machine # [ 73.562867] systemd[1]: Stopped target Local File Systems. machine # [ 73.764752] systemd[1]: Reload requested from client PID 6601 ('.switch-to-conf') (unit backdoor.service)... machine # [ 73.766423] systemd[1]: Reloading... machine # [ 73.998091] systemd[1]: Reloading finished in 230 ms. machine # [ 74.057878] systemd[619]: Reexecution requested from client PID 6670 ('switch-to-confi')... machine # [ 74.059674] systemd[619]: Reexecuting. machine # [ 74.079048] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 74.152669] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 74.157717] systemd[619]: Reload requested from client PID 6670 ('switch-to-confi')... machine # [ 74.159699] systemd[619]: Reloading... machine # [ 74.232178] systemd[619]: Reloading finished in 71 ms. machine # [ 74.237863] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 74.246056] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 74.246963] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 74.248568] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 74.268788] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 74.271810] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 74.273190] systemd[1]: Stopping Reactivate sysinit units... machine # [ 74.273963] systemd[1]: Reached target Reactivate sysinit units. machine # [ 74.275668] systemd[1]: Reached target Remote File Systems. machine # [ 74.279769] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 74.281850] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 74.289063] systemd[1]: Starting linger-users.service... machine # [ 74.291771] systemd[1]: Reached target Local File Systems. machine # [ 74.293916] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 74.297175] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 74.297912] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 74.300749] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 74.304078] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 74.307203] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 74.310183] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 74.353747] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 74.355967] systemd[1]: Finished linger-users.service. machine # [ 74.451488] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 74.453443] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 74.708369] nixos[6601]: finished switching to system configuration /nix/store/0c35hqwpj9ry3qq2yjbr6vp5a6182isv-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedSomethingElse/bin/switch-to-configuration test 2>&1, in 1.34 seconds) (finished: subtest: unit file parser, in 18.55 seconds) subtest: restart and reload by activation script machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1 machine # [ 74.879798] nixos[6741]: switching to system configuration /nix/store/681igddh6k2migcwdmbdjw0vivlgm5la-nixos-system-machine-test machine # [ 74.883256] systemd[1]: Stopped target Local File Systems. machine # [ 74.883976] systemd[1]: Stopped target Remote File Systems. machine # [ 75.088677] systemd[1]: Reload requested from client PID 6741 ('.switch-to-conf') (unit backdoor.service)... machine # [ 75.090379] systemd[1]: Reloading... machine # [ 75.302167] systemd[1]: Reloading finished in 210 ms. machine # [ 75.357713] systemd[619]: Reexecution requested from client PID 6810 ('switch-to-confi')... machine # [ 75.360189] systemd[619]: Reexecuting. machine # [ 75.380300] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 75.453088] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 75.458340] systemd[619]: Reload requested from client PID 6810 ('switch-to-confi')... machine # [ 75.459752] systemd[619]: Reloading... machine # [ 75.534959] systemd[619]: Reloading finished in 74 ms. machine # [ 75.539876] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 75.546609] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 75.548197] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 75.550242] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 75.569447] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 75.572439] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 75.573670] systemd[1]: Stopping Reactivate sysinit units... machine # [ 75.574686] systemd[1]: Reached target Reactivate sysinit units. machine # [ 75.576916] systemd[1]: Reached target Local File Systems. machine # [ 75.579204] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 75.581422] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 75.583812] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 75.585228] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 75.587255] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 75.588698] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 75.589876] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 75.594471] systemd[1]: Reached target Remote File Systems. machine # [ 75.604989] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 75.606506] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 75.729924] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 75.731647] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 75.735427] systemd[1]: Starting linger-users.service... machine # [ 75.762194] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 75.763624] systemd[1]: Finished linger-users.service. machine # [ 76.021425] nixos[6741]: finished switching to system configuration /nix/store/681igddh6k2migcwdmbdjw0vivlgm5la-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1, in 1.32 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1 machine # [ 76.255962] nixos[6881]: switching to system configuration /nix/store/3vr6dxs4qak6jr6cyfq3hhnf49blxzhr-nixos-system-machine-test machine # [ 76.258343] systemd[1]: Stopped target Remote File Systems. machine # [ 76.260058] systemd[1]: Stopped target Local File Systems. machine # [ 76.261541] systemd[1]: test.service: Deactivated successfully. machine # [ 76.262932] systemd[1]: Stopped test.service. machine # [ 76.496275] systemd[1]: Reload requested from client PID 6881 ('.switch-to-conf') (unit backdoor.service)... machine # [ 76.498200] systemd[1]: Reloading... machine # [ 76.755222] systemd[1]: Reloading finished in 255 ms. machine # [ 76.817492] systemd[619]: Reexecution requested from client PID 6952 ('switch-to-confi')... machine # [ 76.818780] systemd[619]: Reexecuting. machine # [ 76.838966] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 76.913024] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 76.918946] systemd[619]: Reload requested from client PID 6952 ('switch-to-confi')... machine # [ 76.920617] systemd[619]: Reloading... machine # [ 76.992768] systemd[619]: Reloading finished in 70 ms. machine # [ 76.996827] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 77.002807] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 77.004651] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 77.006251] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 77.025584] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 77.028494] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 77.029692] systemd[1]: Stopping Reactivate sysinit units... machine # [ 77.030739] systemd[1]: Reached target Reactivate sysinit units. machine # [ 77.035563] systemd[1]: Created slice Slice /system/templated-no-restart-service. machine # [ 77.038408] systemd[1]: Starting templated-no-restart-service@instance.service... machine # [ 77.046039] systemd[1]: Created slice Slice /system/templated-simple-service. machine # [ 77.048885] systemd[1]: Starting templated-simple-service@instance.service... machine # [ 77.057164] systemd[1]: Created slice Slice /system/templated-simple-restart-service. machine # [ 77.059373] systemd[1]: Starting templated-simple-restart-service@instance.service... machine # [ 77.069787] systemd[1]: Created slice Slice /system/templated-reload-triggers-and-restart-by-as. machine # [ 77.073067] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 77.078109] systemd[1]: Starting no-restart-service.service... machine # [ 77.091394] systemd[1]: Created slice Slice /system/templated-simple-reload-service. machine # [ 77.094796] systemd[1]: Starting templated-simple-reload-service@instance.service... machine # [ 77.102972] systemd[1]: Reached target Local File Systems. machine # [ 77.106354] systemd[1]: Finished templated-no-restart-service@instance.service. machine # [ 77.110319] systemd[1]: Created slice Slice /system/templated-reload-triggers. machine # [ 77.115297] systemd[1]: Created slice Slice /system/templated-reload-triggers-and-restart. machine # [ 77.121736] systemd[1]: Reached target Remote File Systems. machine # [ 77.126243] systemd[1]: Starting linger-users.service... machine # [ 77.126934] systemd[1]: Starting reload-triggers-and-restart.service... machine # [ 77.131686] systemd[1]: Starting reload-triggers.service... machine # [ 77.138185] systemd[1]: Starting templated-reload-triggers-and-restart@instance.service... machine # [ 77.139226] systemd[1]: Starting templated-reload-triggers@instance.service... machine # [ 77.141926] systemd[1]: Starting simple-service.service... machine # [ 77.157218] systemd[1]: Starting simple-reload-service.service... machine # [ 77.173237] systemd[1]: Starting simple-restart-service.service... machine # [ 77.180170] systemd[1]: Starting reload-triggers-and-restart-by-as.service... machine # [ 77.186770] systemd[1]: Finished templated-simple-service@instance.service. machine # [ 77.205077] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 77.211162] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 77.222735] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 77.224128] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 77.224899] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 77.229133] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 77.235728] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 77.244263] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 77.248128] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 77.252066] systemd[1]: Finished templated-simple-restart-service@instance.service. machine # [ 77.261833] systemd[1]: Finished no-restart-service.service. machine # [ 77.268305] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 77.290235] systemd[1]: Finished templated-simple-reload-service@instance.service. machine # [ 77.299801] systemd[1]: Finished templated-reload-triggers@instance.service. machine # [ 77.302607] systemd[1]: Finished reload-triggers.service. machine # [ 77.305101] systemd[1]: Finished reload-triggers-and-restart.service. machine # [ 77.313889] systemd[1]: Finished templated-reload-triggers-and-restart@instance.service. machine # [ 77.315231] systemd[1]: Finished simple-service.service. machine # [ 77.333332] systemd[1]: Finished simple-reload-service.service. machine # [ 77.334294] systemd[1]: Finished reload-triggers-and-restart-by-as.service. machine # [ 77.335834] systemd[1]: Finished simple-restart-service.service. machine # [ 77.354833] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 77.358286] systemd[1]: Finished linger-users.service. machine # [ 77.464086] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 77.465737] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 77.722470] nixos[6881]: finished switching to system configuration /nix/store/3vr6dxs4qak6jr6cyfq3hhnf49blxzhr-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1, in 1.70 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1 machine # [ 77.943065] nixos[7051]: switching to system configuration /nix/store/3vr6dxs4qak6jr6cyfq3hhnf49blxzhr-nixos-system-machine-test machine # [ 77.945294] systemd[1]: Stopped target Local File Systems. machine # [ 77.946751] systemd[1]: Stopped target Remote File Systems. machine # [ 78.165325] systemd[1]: Reload requested from client PID 7051 ('.switch-to-conf') (unit backdoor.service)... machine # [ 78.166973] systemd[1]: Reloading... machine # [ 78.416308] systemd[1]: Reloading finished in 247 ms. machine # [ 78.480765] systemd[619]: Reexecution requested from client PID 7122 ('switch-to-confi')... machine # [ 78.483297] systemd[619]: Reexecuting. machine # [ 78.504994] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 78.579649] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 78.585020] systemd[619]: Reload requested from client PID 7122 ('switch-to-confi')... machine # [ 78.586668] systemd[619]: Reloading... machine # [ 78.662364] systemd[619]: Reloading finished in 74 ms. machine # [ 78.667887] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 78.675066] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 78.675944] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 78.677793] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 78.696985] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 78.699844] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 78.701179] systemd[1]: Stopping Reactivate sysinit units... machine # [ 78.701901] systemd[1]: Reached target Reactivate sysinit units. machine # [ 78.706436] systemd[1]: Reloading reload-triggers-and-restart.service... machine # [ 78.709967] systemd[1]: Reloading reload-triggers.service... machine # [ 78.716248] systemd[1]: Reloading simple-reload-service.service... machine # [ 78.721655] systemd[1]: Reloading templated-reload-triggers-and-restart@instance.service... machine # [ 78.727536] systemd[1]: Reloading templated-reload-triggers@instance.service... machine # [ 78.728587] systemd[1]: Reloading templated-simple-reload-service@instance.service... machine # [ 78.774666] systemd[1]: Reloaded reload-triggers-and-restart.service. machine # [ 78.778678] systemd[1]: Reloaded reload-triggers.service. machine # [ 78.780587] systemd[1]: Reloaded simple-reload-service.service. machine # [ 78.785955] systemd[1]: Reloaded templated-reload-triggers-and-restart@instance.service. machine # [ 78.788551] systemd[1]: Reloaded templated-reload-triggers@instance.service. machine # [ 78.790553] systemd[1]: Reloaded templated-simple-reload-service@instance.service. machine # [ 78.794325] systemd[1]: reload-triggers-and-restart-by-as.service: Deactivated successfully. machine # [ 78.796282] systemd[1]: Stopped reload-triggers-and-restart-by-as.service. machine # [ 78.797228] systemd[1]: Stopping reload-triggers-and-restart-by-as.service... machine # [ 78.800201] systemd[1]: Starting reload-triggers-and-restart-by-as.service... machine # [ 78.801184] systemd[1]: simple-restart-service.service: Deactivated successfully. machine # [ 78.802093] systemd[1]: Stopped simple-restart-service.service. machine # [ 78.802880] systemd[1]: Stopping simple-restart-service.service... machine # [ 78.806215] systemd[1]: Starting simple-restart-service.service... machine # [ 78.808183] systemd[1]: simple-service.service: Deactivated successfully. machine # [ 78.810081] systemd[1]: Stopped simple-service.service. machine # [ 78.810804] systemd[1]: Stopping simple-service.service... machine # [ 78.813188] systemd[1]: Starting simple-service.service... machine # [ 78.816456] systemd[1]: templated-reload-triggers-and-restart-by-as@instance.service: Deactivated successfully. machine # [ 78.819133] systemd[1]: Stopped templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 78.820202] systemd[1]: Stopping templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 78.824345] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 78.825491] systemd[1]: templated-simple-restart-service@instance.service: Deactivated successfully. machine # [ 78.826630] systemd[1]: Stopped templated-simple-restart-service@instance.service. machine # [ 78.827611] systemd[1]: Stopping templated-simple-restart-service@instance.service... machine # [ 78.831515] systemd[1]: Starting templated-simple-restart-service@instance.service... machine # [ 78.836491] systemd[1]: templated-simple-service@instance.service: Deactivated successfully. machine # [ 78.837640] systemd[1]: Stopped templated-simple-service@instance.service. machine # [ 78.838613] systemd[1]: Stopping templated-simple-service@instance.service... machine # [ 78.841247] systemd[1]: Starting templated-simple-service@instance.service... machine # [ 78.880935] systemd[1]: Finished reload-triggers-and-restart-by-as.service. machine # [ 78.885174] systemd[1]: Finished simple-restart-service.service. machine # [ 78.890940] systemd[1]: Finished simple-service.service. machine # [ 78.895545] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 78.898716] systemd[1]: Finished templated-simple-restart-service@instance.service. machine # [ 78.900887] systemd[1]: Finished templated-simple-service@instance.service. machine # [ 78.906147] systemd[1]: Reached target Local File Systems. machine # [ 78.908551] systemd[1]: Reached target Remote File Systems. machine # [ 78.910556] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 78.912265] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 78.916812] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 78.921274] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 78.922838] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 78.924292] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 78.926169] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 78.927543] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 78.928662] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 79.053622] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 79.056278] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 79.059058] systemd[1]: Starting linger-users.service... machine # [ 79.086269] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 79.087651] systemd[1]: Finished linger-users.service. machine # [ 79.342071] nixos[7051]: finished switching to system configuration /nix/store/3vr6dxs4qak6jr6cyfq3hhnf49blxzhr-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1, in 1.62 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script-modified/bin/switch-to-configuration test 2>&1 machine # [ 79.586375] nixos[7217]: switching to system configuration /nix/store/77nil1a67zii16qw94xc9qr4109xrw5l-nixos-system-machine-test machine # [ 79.589771] systemd[1]: Stopped target Remote File Systems. machine # [ 79.590733] systemd[1]: Stopped target Local File Systems. machine # [ 79.833909] systemd[1]: Reload requested from client PID 7217 ('.switch-to-conf') (unit backdoor.service)... machine # [ 79.835588] systemd[1]: Reloading... machine # [ 80.102149] systemd[1]: Reloading finished in 265 ms. machine # [ 80.169273] systemd[619]: Reexecution requested from client PID 7288 ('switch-to-confi')... machine # [ 80.171123] systemd[619]: Reexecuting. machine # [ 80.192099] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 80.265982] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 80.271066] systemd[619]: Reload requested from client PID 7288 ('switch-to-confi')... machine # [ 80.272494] systemd[619]: Reloading... machine # [ 80.346375] systemd[619]: Reloading finished in 72 ms. machine # [ 80.351821] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 80.359102] systemd[619]: Stopped Run user-specific NixOS activation. machine # [ 80.359962] systemd[619]: Stopping Run user-specific NixOS activation... machine # [ 80.361711] systemd[619]: Starting Run user-specific NixOS activation... machine # [ 80.381166] systemd[619]: Finished Run user-specific NixOS activation. machine # [ 80.383957] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 80.385243] systemd[1]: Stopping Reactivate sysinit units... machine # [ 80.385994] systemd[1]: Reached target Reactivate sysinit units. machine # [ 80.390292] systemd[1]: Reloading reload-triggers.service... machine # [ 80.393611] systemd[1]: Reloading simple-reload-service.service... machine # [ 80.400149] systemd[1]: Reloading templated-reload-triggers@instance.service... machine # [ 80.405061] systemd[1]: Reloading templated-simple-reload-service@instance.service... machine # [ 80.437991] systemd[1]: Reloaded reload-triggers.service. machine # [ 80.439950] systemd[1]: Reloaded simple-reload-service.service. machine # [ 80.441840] systemd[1]: Reloaded templated-reload-triggers@instance.service. machine # [ 80.443857] systemd[1]: Reloaded templated-simple-reload-service@instance.service. machine # [ 80.446858] systemd[1]: reload-triggers-and-restart-by-as.service: Deactivated successfully. machine # [ 80.449131] systemd[1]: Stopped reload-triggers-and-restart-by-as.service. machine # [ 80.450089] systemd[1]: Stopping reload-triggers-and-restart-by-as.service... machine # [ 80.452068] systemd[1]: Starting reload-triggers-and-restart-by-as.service... machine # [ 80.453906] systemd[1]: reload-triggers-and-restart.service: Deactivated successfully. machine # [ 80.456279] systemd[1]: Stopped reload-triggers-and-restart.service. machine # [ 80.457640] systemd[1]: Stopping reload-triggers-and-restart.service... machine # [ 80.459287] systemd[1]: Starting reload-triggers-and-restart.service... machine # [ 80.462168] systemd[1]: simple-restart-service.service: Deactivated successfully. machine # [ 80.464221] systemd[1]: Stopped simple-restart-service.service. machine # [ 80.464977] systemd[1]: Stopping simple-restart-service.service... machine # [ 80.468278] systemd[1]: Starting simple-restart-service.service... machine # [ 80.470119] systemd[1]: simple-service.service: Deactivated successfully. machine # [ 80.471055] systemd[1]: Stopped simple-service.service. machine # [ 80.471783] systemd[1]: Stopping simple-service.service... machine # [ 80.476411] systemd[1]: Starting simple-service.service... machine # [ 80.477634] systemd[1]: templated-reload-triggers-and-restart-by-as@instance.service: Deactivated successfully. machine # [ 80.479119] systemd[1]: Stopped templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 80.480176] systemd[1]: Stopping templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 80.487436] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 80.488572] systemd[1]: templated-reload-triggers-and-restart@instance.service: Deactivated successfully. machine # [ 80.489796] systemd[1]: Stopped templated-reload-triggers-and-restart@instance.service. machine # [ 80.490806] systemd[1]: Stopping templated-reload-triggers-and-restart@instance.service... machine # [ 80.496305] systemd[1]: Starting templated-reload-triggers-and-restart@instance.service... machine # [ 80.497631] systemd[1]: templated-simple-restart-service@instance.service: Deactivated successfully. machine # [ 80.504580] systemd[1]: Stopped templated-simple-restart-service@instance.service. machine # [ 80.507287] systemd[1]: Stopping templated-simple-restart-service@instance.service... machine # [ 80.510135] systemd[1]: Starting templated-simple-restart-service@instance.service... machine # [ 80.513336] systemd[1]: templated-simple-service@instance.service: Deactivated successfully. machine # [ 80.517073] systemd[1]: Stopped templated-simple-service@instance.service. machine # [ 80.520093] systemd[1]: Stopping templated-simple-service@instance.service... machine # [ 80.523483] systemd[1]: Starting templated-simple-service@instance.service... machine # [ 80.561091] systemd[1]: Finished reload-triggers-and-restart-by-as.service. machine # [ 80.564428] systemd[1]: Finished reload-triggers-and-restart.service. machine # [ 80.566281] systemd[1]: Finished simple-restart-service.service. machine # [ 80.574890] systemd[1]: Finished simple-service.service. machine # [ 80.578918] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 80.585799] systemd[1]: Finished templated-simple-restart-service@instance.service. machine # [ 80.588194] systemd[1]: Finished templated-reload-triggers-and-restart@instance.service. machine # [ 80.590493] systemd[1]: Finished templated-simple-service@instance.service. machine # [ 80.595582] systemd[1]: Reached target Local File Systems. machine # [ 80.597857] systemd[1]: Reached target Remote File Systems. machine # [ 80.602389] systemd[1]: Starting linger-users.service... machine # [ 80.608803] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 80.610993] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 80.615159] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 80.619086] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 80.619860] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 80.622720] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 80.625520] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 80.627101] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 80.630099] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 80.659587] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 80.661629] systemd[1]: Finished linger-users.service. machine # [ 80.773118] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 80.774880] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 81.032940] nixos[7217]: finished switching to system configuration /nix/store/77nil1a67zii16qw94xc9qr4109xrw5l-nixos-system-machine-test machine: (finished: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script-modified/bin/switch-to-configuration test 2>&1, in 1.69 seconds) machine: must succeed: /nix/store/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-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/5hgxmlg6wd7x4ajc9j829za7sc4hzqpr-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration dry-activate 2>&1, in 0.34 seconds) (finished: subtest: restart and reload by activation script, in 6.67 seconds) (finished: run the VM test script, in 82.33 seconds) test script finished in 82.53s cleanup kill QemuMachine (pid 45) machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14) machine # [2026-09-14T03:05:22Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-14T03:05:22Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-14T03:05:22Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.20 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