tribuchet: building on jamie Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script machine: waiting for unit multi-user.target machine: waiting for the VM to finish booting machine: starting vm machine: QEMU running (pid 45) machine # Disk image does not exist, creating the virtualisation disk image... machine # Formatting '/build/vm-state-machine/tmp.7AXWdgx9AX', 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: 41960d63-b1ab-442a-a853-a01ca50c13b1 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-10-19T03:00:11Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-10-19T03:00:11Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-10-19T03:00:11Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-10-19T03:00:11Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-10-19T03:00:11Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-10-19T03:00:11Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-10-19T03:00:11Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-10-19T03:00:11Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-10-19T03:00:11Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-10-19T03:00:11Z INFO virtiofsd] Client connected, servicing requests machine # [2026-10-19T03:00:11Z INFO virtiofsd] Client connected, servicing requests machine # [2026-10-19T03:00:11Z 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)... ok[ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC @1791725172 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/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/init regInfo=/nix/.ro-store/z8sw7ardfdknvrpjrb5wj5f4mqaa4r3c-closure-info/registration console=ttyS0,115200n8 console=tty0 machine # [ 0.000000] BIOS-provided physical RAM map: machine # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable machine # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable machine # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved machine # [ 0.000000] NX (Execute Disable) protection: active machine # [ 0.000000] APIC: Static calls initialized machine # [ 0.000000] SMBIOS 2.8 present. machine # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014 machine # [ 0.000000] DMI: Memory slots populated: 1/1 machine # [ 0.000000] Hypervisor detected: KVM machine # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 machine # [ 0.000000] kvm-clock: using sched offset of 468833842 cycles machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns machine # [ 0.000005] tsc: Detected 2400.008 MHz processor machine # [ 0.000765] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 machine # [ 0.000793] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs machine # [ 0.000796] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT machine # [ 0.002694] found SMP MP-table at [mem 0x000f5450-0x000f545f] machine # [ 0.002707] Using GB pages for direct mapping machine # [ 0.002770] RAMDISK: [mem 0x3e2b5000-0x3ffcffff] machine # [ 0.002779] ACPI: Early table checksum verification disabled machine # [ 0.002782] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) machine # [ 0.002786] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002791] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002799] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002801] ACPI: FACS 0x000000003FFE0000 000040 machine # [ 0.002802] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002804] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002805] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002807] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002808] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d] machine # [ 0.002809] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219] machine # [ 0.002810] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f] machine # [ 0.002810] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385] machine # [ 0.002811] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd] machine # [ 0.002811] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9] machine # [ 0.002812] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421] machine # [ 0.003039] No NUMA configuration found machine # [ 0.003040] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff] machine # [ 0.003043] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff] machine # [ 0.005432] Zone ranges: machine # [ 0.005433] DMA [mem 0x0000000000001000-0x0000000000ffffff] machine # [ 0.005435] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff] machine # [ 0.005436] Normal empty machine # [ 0.005437] Device empty machine # [ 0.005437] Movable zone start for each node machine # [ 0.005438] Early memory node ranges machine # [ 0.005438] node 0: [mem 0x0000000000001000-0x000000000009efff] machine # [ 0.005439] node 0: [mem 0x0000000000100000-0x000000003ffd7fff] machine # [ 0.005440] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff] machine # [ 0.005462] On node 0, zone DMA: 1 pages in unavailable ranges machine # [ 0.005739] On node 0, zone DMA: 97 pages in unavailable ranges machine # [ 0.024482] On node 0, zone DMA32: 40 pages in unavailable ranges machine # [ 0.024933] ACPI: PM-Timer IO Port: 0x608 machine # [ 0.024945] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) machine # [ 0.024974] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 machine # [ 0.024977] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) machine # [ 0.024979] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) machine # [ 0.024980] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) machine # [ 0.024981] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) machine # [ 0.024982] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) machine # [ 0.024984] ACPI: Using ACPI (MADT) for SMP configuration information machine # [ 0.024985] ACPI: HPET id: 0x8086a201 base: 0xfed00000 machine # [ 0.024988] TSC deadline timer available machine # [ 0.024992] CPU topo: Max. logical packages: 1 machine # [ 0.024993] CPU topo: Max. logical dies: 1 machine # [ 0.024994] CPU topo: Max. dies per package: 1 machine # [ 0.024997] CPU topo: Max. threads per core: 1 machine # [ 0.024998] CPU topo: Num. cores per package: 1 machine # [ 0.024998] CPU topo: Num. threads per package: 1 machine # [ 0.024998] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs machine # [ 0.025015] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() machine # [ 0.025047] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] machine # [ 0.025048] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] machine # [ 0.025050] [mem 0x40000000-0xafffffff] available for PCI devices machine # [ 0.025051] Booting paravirtualized kernel on KVM machine # [ 0.025054] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns machine # [ 0.029747] setup_percpu: NR_CPUS:512 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 machine # [ 0.032059] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152 machine # [ 0.032105] kvm-guest: PV spinlocks disabled, single CPU machine # [ 0.032106] 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/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/init regInfo=/nix/.ro-store/z8sw7ardfdknvrpjrb5wj5f4mqaa4r3c-closure-info/registration console=ttyS0,115200n8 console=tty0 machine # [ 0.032201] Unknown kernel command line parameters "regInfo=/nix/.ro-store/z8sw7ardfdknvrpjrb5wj5f4mqaa4r3c-closure-info/registration", will be passed to user space. machine # [ 0.032213] random: crng init done machine # [ 0.032214] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes machine # [ 0.032235] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.032812] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.032841] Fallback order for Node 0: 0 machine # [ 0.032844] Built 1 zonelists, mobility grouping on. Total pages: 262006 machine # [ 0.032845] Policy zone: DMA32 machine # [ 0.035399] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.037961] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 machine # [ 0.040347] allocated 2097152 bytes of page_ext machine # [ 0.050399] ftrace: allocating 48842 entries in 192 pages machine # [ 0.050401] ftrace: allocated 192 pages with 2 groups machine # [ 0.051285] Dynamic Preempt: lazy machine # [ 0.051430] rcu: Preemptible hierarchical RCU implementation. machine # [ 0.051431] rcu: RCU event tracing is enabled. machine # [ 0.051431] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1. machine # [ 0.051433] Trampoline variant of Tasks RCU enabled. machine # [ 0.051434] Rude variant of Tasks RCU enabled. machine # [ 0.051434] Tracing variant of Tasks RCU enabled. machine # [ 0.051435] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. machine # [ 0.051436] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 machine # [ 0.051446] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.051448] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.051449] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.055807] NR_IRQS: 33024, nr_irqs: 256, preallocated irqs: 16 machine # [ 0.056129] rcu: srcu_init: Setting srcu_struct sizes based on contention. machine # [ 0.056136] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns machine # [ 0.056242] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.059764] Console: colour VGA+ 80x25 machine # [ 0.059767] printk: legacy console [tty0] enabled machine # [ 0.089388] printk: legacy console [ttyS0] enabled machine # [ 0.196870] ACPI: Core revision 20250807 machine # [ 0.197799] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns machine # [ 0.199423] APIC: Switch to symmetric I/O mode setup machine # [ 0.200488] x2apic enabled machine # [ 0.201244] APIC: Switched APIC routing to: physical x2apic machine # [ 0.203211] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 machine # [ 0.204344] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22983f10a64, max_idle_ns: 440795218721 ns machine # [ 0.206078] Calibrating delay loop (skipped) preset value.. 4800.01 BogoMIPS (lpj=2400008) machine # [ 0.208163] x86/cpu: User Mode Instruction Prevention (UMIP) activated machine # [ 0.209206] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 machine # [ 0.210076] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 machine # [ 0.211079] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto machine # [ 0.212075] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl machine # [ 0.213075] Transient Scheduler Attacks: Vulnerable: No microcode machine # [ 0.214864] Spectre V2 : Mitigation: Enhanced / Automatic IBRS machine # [ 0.215837] Speculative Return Stack Overflow: Mitigation: Safe RET machine # [ 0.216855] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization machine # [ 0.218083] Spectre V2 : Enabling IBPB for BPF machine # [ 0.219648] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier machine # [ 0.220076] active return thunk: srso_alias_return_thunk machine # [ 0.221087] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' machine # [ 0.222075] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' machine # [ 0.223075] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' machine # [ 0.224075] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' machine # [ 0.225075] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' machine # [ 0.226075] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' machine # [ 0.227075] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' machine # [ 0.229075] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 machine # [ 0.230075] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 machine # [ 0.231075] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 machine # [ 0.232075] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 machine # [ 0.233075] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 machine # [ 0.234075] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format. machine # [ 0.268583] Freeing SMP alternatives memory: 48K machine # [ 0.269078] pid_max: default: 32768 minimum: 301 machine # [ 0.270168] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.271182] landlock: Up and running. machine # [ 0.272076] Yama: becoming mindful. machine # [ 0.272917] LSM support for eBPF active machine # [ 0.273636] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.275080] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.277393] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1) machine # [ 0.278678] Performance Events: Fam17h+ core perfctr, AMD PMU driver. machine # [ 0.279085] ... version: 2 machine # [ 0.279833] ... bit width: 48 machine # [ 0.280078] ... generic counters: 6 machine # [ 0.280817] ... generic bitmap: 000000000000003f machine # [ 0.281078] ... fixed-purpose counters: 0 machine # [ 0.281846] ... fixed-purpose bitmap: 0000000000000000 machine # [ 0.282077] ... value mask: 0000ffffffffffff machine # [ 0.283037] ... max period: 00007fffffffffff machine # [ 0.283819] ... global_ctrl mask: 000000000000003f machine # [ 0.284175] signal: max sigframe size: 3376 machine # [ 0.285008] rcu: Hierarchical SRCU implementation. machine # [ 0.285726] rcu: Max phase no-delay instances is 400. machine # [ 0.290698] smp: Bringing up secondary CPUs ... machine # [ 0.291092] smp: Brought up 1 node, 1 CPU machine # [ 0.291804] smpboot: Total of 1 processors activated (4800.01 BogoMIPS) machine # [ 0.292307] Memory: 940312K/1048024K available (18132K kernel code, 2734K rwdata, 13652K rodata, 3660K init, 3620K bss, 100324K reserved, 0K cma-reserved) machine # [ 0.293334] devtmpfs: initialized machine # [ 0.294111] x86/mm: Memory block size: 128MB machine # [ 0.295880] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.297004] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.298165] pinctrl core: initialized pinctrl subsystem machine # [ 0.299328] PM: RTC time: 03:00:11, date: 2026-10-19 machine # [ 0.303258] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.304462] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.305096] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.306233] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.307089] audit: initializing netlink subsys (disabled) machine # [ 0.308254] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.308257] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.309079] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.310081] audit: type=2000 audit(1792378812.500:1): state=initialized audit_enabled=0 res=1 machine # [ 0.312081] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.312082] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.313091] cpuidle: using governor menu machine # [ 0.315353] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 machine # [ 0.316359] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] machine # [ 0.317081] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry machine # [ 0.318089] PCI: Using configuration type 1 for base access machine # [ 0.319274] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. machine # [ 0.324168] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.325078] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.330079] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.331078] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.341474] ACPI: Added _OSI(Module Device) machine # [ 0.342079] ACPI: Added _OSI(Processor Device) machine # [ 0.342859] ACPI: Added _OSI(Processor Aggregator Device) machine # [ 0.351360] ACPI: 1 ACPI AML tables successfully acquired and loaded machine # [ 0.355522] ACPI: Interpreter enabled machine # [ 0.356096] ACPI: PM: (supports S0 S3 S4 S5) machine # [ 0.356820] ACPI: Using IOAPIC for interrupt routing machine # [ 0.359156] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug machine # [ 0.360078] PCI: Using E820 reservations for host bridge windows machine # [ 0.363219] ACPI: Enabled 2 GPEs in block 00 to 3F machine # [ 0.369564] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) machine # [ 0.370083] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] machine # [ 0.371155] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] machine # [ 0.372199] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] machine # [ 0.373431] PCI host bridge to bus 0000:00 machine # [ 0.374082] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] machine # [ 0.375078] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] machine # [ 0.376077] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] machine # [ 0.377078] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window] machine # [ 0.378078] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] machine # [ 0.379077] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] machine # [ 0.380079] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.381135] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.382521] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint machine # [ 0.385213] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] machine # [ 0.386090] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] machine # [ 0.387147] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] machine # [ 0.388407] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] machine # [ 0.389808] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.393097] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f] machine # [ 0.394017] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] machine # [ 0.394865] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] machine # [ 0.395999] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] machine # [ 0.397456] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.400125] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f] machine # [ 0.401084] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] machine # [ 0.402099] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] machine # [ 0.403640] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.405088] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f] machine # [ 0.405988] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] machine # [ 0.406891] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] machine # [ 0.408775] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.411116] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f] machine # [ 0.412009] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] machine # [ 0.412859] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref] machine # [ 0.414006] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref] machine # [ 0.415465] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.417437] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] machine # [ 0.418101] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref] machine # [ 0.419703] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.421050] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] machine # [ 0.421823] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref] machine # [ 0.423567] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.425090] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] machine # [ 0.426101] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref] machine # [ 0.427630] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.429048] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] machine # [ 0.429903] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref] machine # [ 0.431590] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.433122] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf] machine # [ 0.434048] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] machine # [ 0.434908] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref] machine # [ 0.436570] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.439060] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f] machine # [ 0.439804] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff] machine # [ 0.440100] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref] machine # [ 0.441671] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.442629] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f] machine # [ 0.443282] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.444583] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf] machine # [ 0.445291] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.446986] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df] machine # [ 0.448047] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.450086] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff] machine # [ 0.451314] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint machine # [ 0.452363] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO machine # [ 0.453354] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint machine # [ 0.455909] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff] machine # [ 0.456816] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff] machine # [ 0.457401] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint machine # [ 0.458633] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] machine # [ 0.462116] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 machine # [ 0.463183] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 machine # [ 0.464185] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 machine # [ 0.465176] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 machine # [ 0.466176] ACPI: PCI: Interrupt link LNKE configured for IRQ 10 machine # [ 0.467174] ACPI: PCI: Interrupt link LNKF configured for IRQ 10 machine # [ 0.468176] ACPI: PCI: Interrupt link LNKG configured for IRQ 11 machine # [ 0.469175] ACPI: PCI: Interrupt link LNKH configured for IRQ 11 machine # [ 0.470116] ACPI: PCI: Interrupt link GSIA configured for IRQ 16 machine # [ 0.471093] ACPI: PCI: Interrupt link GSIB configured for IRQ 17 machine # [ 0.472090] ACPI: PCI: Interrupt link GSIC configured for IRQ 18 machine # [ 0.473090] ACPI: PCI: Interrupt link GSID configured for IRQ 19 machine # [ 0.474093] ACPI: PCI: Interrupt link GSIE configured for IRQ 20 machine # [ 0.475093] ACPI: PCI: Interrupt link GSIF configured for IRQ 21 machine # [ 0.476092] ACPI: PCI: Interrupt link GSIG configured for IRQ 22 machine # [ 0.477089] ACPI: PCI: Interrupt link GSIH configured for IRQ 23 machine # [ 0.479108] iommu: Default domain type: Translated machine # [ 0.479942] iommu: DMA domain TLB invalidation policy: lazy mode machine # [ 0.481150] ACPI: bus type USB registered machine # [ 0.481918] usbcore: registered new interface driver usbfs machine # [ 0.482809] usbcore: registered new interface driver hub machine # [ 0.483085] usbcore: registered new device driver usb machine # [ 0.484960] NetLabel: Initializing machine # [ 0.485575] NetLabel: domain hash size = 128 machine # [ 0.486078] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO machine # [ 0.487104] NetLabel: unlabeled traffic allowed by default machine # [ 0.488061] PCI: Using ACPI for IRQ routing machine # [ 0.531934] pci 0000:00:01.0: vgaarb: setting as boot VGA device machine # [ 0.532074] pci 0000:00:01.0: vgaarb: bridge control possible machine # [ 0.532074] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none machine # [ 0.532083] vgaarb: loaded machine # [ 0.532861] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 machine # [ 0.533078] hpet0: 3 comparators, 64-bit 100.000000 MHz counter machine # [ 0.537167] clocksource: Switched to clocksource kvm-clock machine # [ 0.538923] VFS: Disk quotas dquot_6.6.0 machine # [ 0.541485] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.542897] pnp: PnP ACPI init machine # [ 0.543728] ACPI: IRQ 4 override to edge(!), high(!) machine # [ 0.544727] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved machine # [ 0.546320] pnp: PnP ACPI: found 5 devices machine # [ 0.553966] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns machine # [ 0.555455] clocksource: Switched to clocksource acpi_pm machine # [ 0.556462] NET: Registered PF_INET protocol family machine # [ 0.557535] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.575084] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.576666] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.578047] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.579424] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.580726] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.581889] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.583216] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.584378] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.585613] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.586600] NET: Registered PF_XDP protocol family machine # [ 0.587473] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] machine # [ 0.588524] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] machine # [ 0.589586] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] machine # [ 0.590720] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window] machine # [ 0.591893] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] machine # [ 0.593068] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] machine # [ 0.594869] ACPI: \_SB_.GSIA: Enabled at IRQ 16 machine # [ 0.597229] ACPI: \_SB_.GSIB: Enabled at IRQ 17 machine # [ 0.599318] ACPI: \_SB_.GSIC: Enabled at IRQ 18 machine # [ 0.601361] ACPI: \_SB_.GSID: Enabled at IRQ 19 machine # [ 0.603123] PCI: CLS 0 bytes, default 64 machine # [ 0.604066] Trying to unpack rootfs image as initramfs... machine # [ 0.610046] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22983f10a64, max_idle_ns: 440795218721 ns machine # [ 0.653383] Initialise system trusted keyrings machine # [ 0.654341] workingset: timestamp_bits=40 max_order=18 bucket_order=0 machine # [ 0.674849] Key type asymmetric registered machine # [ 0.678976] Asymmetric key parser 'x509' registered machine # [ 0.679872] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) machine # [ 0.683075] io scheduler mq-deadline registered machine # [ 0.683815] io scheduler kyber registered machine # [ 0.687602] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.688977] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A machine # [ 0.692561] Linux agpgart interface v0.103 machine # [ 0.693418] ACPI: bus type drm_connector registered machine # [ 0.696405] usbcore: registered new interface driver usbserial_generic machine # [ 0.697524] usbserial: USB Serial support registered for generic machine # [ 0.700971] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS. machine # [ 0.700971] Please enable it if your BIOS has the CPPC option. machine # [ 0.703317] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled machine # [ 0.707083] drop_monitor: Initializing network drop monitor service machine # [ 0.708280] NET: Registered PF_INET6 protocol family machine # [ 0.711445] Segment Routing with IPv6 machine # [ 0.714115] In-situ OAM (IOAM) with IPv6 machine # [ 0.715205] IPI shorthand broadcast: enabled machine # [ 0.723484] sched_clock: Marking stable (578015448, 144938338)->(795590066, -72636280) machine # [ 0.729141] registered taskstats version 1 machine # [ 0.730188] Loading compiled-in X.509 certificates machine # [ 0.748975] Demotion targets for Node 0: null machine # [ 0.749830] Key type .fscrypt registered machine # [ 0.752957] Key type fscrypt-provisioning registered machine # [ 0.753866] ima: No TPM chip found, activating TPM-bypass! machine # [ 0.754740] ima: Allocated hash algorithm: sha1 machine # [ 0.756992] ima: No architecture policies found machine # [ 0.757896] PM: Magic number: 14:728:7 machine # [ 0.761530] RAS: Correctable Errors collector initialized. machine # [ 0.770290] clk: Disabling unused clocks machine # [ 0.773142] PM: genpd: Disabling unused power domains machine # [ 0.966773] Freeing initrd memory: 29804K machine # [ 0.970048] Freeing unused decrypted memory: 2028K machine # [ 0.972856] Freeing unused kernel image (initmem) memory: 3660K machine # [ 0.973902] Write protecting the kernel read-only data: 32768k machine # [ 0.975198] Freeing unused kernel image (text/rodata gap) memory: 296K machine # [ 0.976654] Freeing unused kernel image (rodata/data gap) memory: 684K machine # [ 1.027403] x86/mm: Checked W+X mappings: passed, no W+X pages found. machine # [ 1.028479] Run /init as init process machine # [ 1.039461] systemd[1]: Inserted module 'autofs4' machine # [ 1.059240] fuse: init (API version 7.45) machine # [ 1.066665] ACPI: \_SB_.GSIG: Enabled at IRQ 22 machine # [ 1.069159] ACPI: \_SB_.GSIH: Enabled at IRQ 23 machine # [ 1.072070] ACPI: \_SB_.GSIE: Enabled at IRQ 20 machine # [ 1.073976] ACPI: \_SB_.GSIF: Enabled at IRQ 21 machine # [ 1.079133] virtiofs virtio5: discovered new tag: nix-store machine # [ 1.080620] virtiofs virtio5: virtio_fs_setup_dax: No cache capability machine # [ 1.087101] virtiofs virtio6: discovered new tag: shared machine # [ 1.088552] virtiofs virtio6: virtio_fs_setup_dax: No cache capability machine # [ 1.091872] virtiofs virtio7: discovered new tag: xchg machine # [ 1.093359] virtiofs virtio7: virtio_fs_setup_dax: No cache capability machine # [ 1.116735] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.118577] systemd[1]: systemd 263-7f4df15 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 +EXPERIMENTAL-ENVVAR) machine # [ 1.124043] systemd[1]: Detected virtualization kvm. machine # [ 1.124922] systemd[1]: Detected architecture x86-64. machine # [ 1.125858] systemd[1]: Running in initrd. machine # [ 1.126841] systemd[1]: Initializing machine ID from random generator. machine # [ 1.128070] systemd[1]: Hostname set to . machine # [ 1.199396] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.235201] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.239187] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.240399] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.241862] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.243028] systemd[1]: Reached target Path Units. machine # [ 1.243923] systemd[1]: Reached target Slice Units. machine # [ 1.244844] systemd[1]: Reached target Swaps. machine # [ 1.245656] systemd[1]: Reached target Timer Units. machine # [ 1.246641] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.247899] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.249087] systemd[1]: Listening on Journal Sockets. machine # [ 1.250105] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.251084] systemd[1]: Reached target Socket Units. machine # [ 1.252851] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.256757] systemd[1]: Starting Load Kernel Module configfs... machine # [ 1.266024] systemd[1]: Starting Journal Service... machine # [ 1.275304] systemd[1]: Starting Load Kernel Modules... machine # [ 1.290906] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.299129] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.312186] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.319622] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 1.326356] systemd[1]: Finished Load Kernel Module configfs. machine # [ 1.329204] systemd-journald[65]: Collecting audit messages is disabled. machine # [ 1.332406] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config machine # [ 1.342019] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.346663] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.357633] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.383514] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.392145] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.403043] systemd[1]: Finished Load Kernel Modules. machine # [ 1.412171] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.433496] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.440229] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.445050] systemd[1]: Reached target Local File Systems. machine # [ 1.301576] systemd-modules-load[66]: Inserted module 'dm_mod' machine # [ 1.304383] systemd-modules-load[66]: Inserted module 'virtio_balloon' machine # [ 1.305453] systemd-modules-load[66]: Inserted module 'virtio_gpu' machine # [ 1.454152] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.460210] systemd[1]: Started Journal Service. machine # [ 1.321932] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.336099] systemd[1]: Starting Create System Files and Directories... machine # [ 1.360531] systemd[1]: Finished Create System Files and Directories. machine # [ 1.370555] systemd-udevd[73]: Using default interface naming scheme 'v261'. machine # [ 1.392613] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 1.445176] systemd[1]: Finished Coldplug All udev Devices. machine # [ 1.446854] systemd[1]: Reached target System Initialization. machine # [ 1.447743] systemd[1]: Reached target Basic System. machine # [ 1.728858] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 machine # [ 1.739795] serio: i8042 KBD port at 0x60,0x64 irq 1 machine # [ 1.744470] serio: i8042 AUX port at 0x60,0x64 irq 12 machine # [ 1.746821] virtio_blk virtio2: 1/0/0 default/read/poll queues machine # [ 1.753392] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 1.762576] ehci-pci 0000:00:1d.7: EHCI Host Controller machine # [ 1.763386] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1 machine # [ 1.765091] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000 machine # [ 1.770975] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 machine # [ 1.771826] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18 machine # [ 1.773412] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 1.775553] usb usb1: Product: EHCI Host Controller machine # [ 1.777967] usb usb1: Manufacturer: Linux 6.18.56 ehci_hcd machine # [ 1.778683] usb usb1: SerialNumber: 0000:00:1d.7 machine # [ 1.780271] hub 1-0:1.0: USB hub found machine # [ 1.781801] hub 1-0:1.0: 6 ports detected machine # [ 1.790174] uhci_hcd 0000:00:1d.0: UHCI Host Controller machine # [ 1.802138] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2 machine # [ 1.813037] uhci_hcd 0000:00:1d.0: detected 2 ports machine # [ 1.813801] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c180 machine # [ 1.823678] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 1.824810] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 1.838559] usb usb2: Product: UHCI Host Controller machine # [ 1.697426] systemd[1]: Starting Virtual Console Setup... machine # [ 1.702325] (udev-worker)[91]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 1.852126] usb usb2: Manufacturer: Linux 6.18.56 uhci_hcd machine # [ 1.708747] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line. machine # [ 1.861414] usb usb2: SerialNumber: 0000:00:1d.0 machine # [ 1.867434] SCSI subsystem initialized machine # [ 1.868830] hub 2-0:1.0: USB hub found machine # [ 1.882186] hub 2-0:1.0: 2 ports detected machine # [ 1.892095] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 machine # [ 1.755513] systemd-vconsole-setup[97]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 1.757265] systemd[1]: Finished Virtual Console Setup. machine # [ 1.906111] uhci_hcd 0000:00:1d.1: UHCI Host Controller machine # [ 1.906846] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3 machine # [ 1.764328] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 1.766075] systemd[1]: Reached target Initrd Root Device. machine # [ 1.914452] uhci_hcd 0000:00:1d.1: detected 2 ports machine # [ 1.770666] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 1.920399] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a0 machine # [ 1.921703] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 1.925972] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 1.932983] usb usb3: Product: UHCI Host Controller machine # [ 1.933661] usb usb3: Manufacturer: Linux 6.18.56 uhci_hcd machine # [ 1.936995] usb usb3: SerialNumber: 0000:00:1d.1 machine # [ 1.939393] hub 3-0:1.0: USB hub found machine # [ 1.941001] hub 3-0:1.0: 2 ports detected machine # [ 1.948242] uhci_hcd 0000:00:1d.2: UHCI Host Controller machine # [ 1.949829] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4 machine # [ 1.957106] uhci_hcd 0000:00:1d.2: detected 2 ports machine # [ 1.814327] systemd-fsck[104]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 1.960319] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c0 machine # [ 1.963987] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 1.965400] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 1.825643] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 1.972828] usb usb4: Product: UHCI Host Controller machine # [ 1.973726] usb usb4: Manufacturer: Linux 6.18.56 uhci_hcd machine # [ 1.974978] usb usb4: SerialNumber: 0000:00:1d.2 machine # [ 1.979530] hub 4-0:1.0: USB hub found machine # [ 1.981017] hub 4-0:1.0: 2 ports detected machine # [ 2.012369] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode machine # [ 2.013505] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) machine # [ 2.016231] ahci 0000:00:1f.2: flags: 64bit ncq only machine # [ 2.018968] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 2.020729] scsi host0: ahci machine # [ 2.021916] scsi host1: ahci machine # [ 2.023510] scsi host2: ahci machine # [ 2.024816] scsi host3: ahci machine # [ 2.026205] scsi host4: ahci machine # [ 2.027776] scsi host5: ahci machine # [ 2.028796] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 1 machine # [ 2.030399] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 1 machine # [ 2.031624] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 1 machine # [ 2.032864] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 1 machine # [ 2.034108] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 1 machine # [ 2.035319] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 1 machine # [ 2.149293] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 machine # [ 2.152250] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 machine # [ 2.155493] usb 1-1: Product: QEMU USB Tablet machine # [ 2.157376] usb 1-1: Manufacturer: QEMU machine # [ 2.159100] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1 machine # [ 2.190632] hid: raw HID events driver (C) Jiri Kosina machine # [ 2.168564] systemd[1]: Mounting /sysroot... machine # [ 2.343153] ata5: SATA link down (SStatus 0 SControl 300) machine # [ 2.345288] ata1: SATA link down (SStatus 0 SControl 300) machine # [ 2.347699] ata4: SATA link down (SStatus 0 SControl 300) machine # [ 2.352261] ata6: SATA link down (SStatus 0 SControl 300) machine # [ 2.354617] ata2: SATA link down (SStatus 0 SControl 300) machine # [ 2.359260] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) machine # [ 2.361726] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 machine # [ 2.367984] ata3.00: applying bridge limits machine # [ 2.369469] ata3.00: configured for UDMA/100 machine # [ 2.373523] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 machine # [ 2.440186] usbcore: registered new interface driver usbhid machine # [ 2.443448] usbhid: USB HID core driver machine # [ 2.456092] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray machine # [ 2.470160] 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.474329] cdrom: Uniform CD-ROM driver Revision: 3.20 machine # [ 2.475272] 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.493382] EXT4-fs (vda): mounted filesystem 41960d63-b1ab-442a-a853-a01ca50c13b1 r/w with ordered data mode. Quota mode: none. machine # [ 2.355607] systemd[1]: Mounted /sysroot. machine # [ 2.358068] systemd[1]: Reached target Initrd Root File System. machine # [ 2.361092] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 2.374092] systemd-sysroot-fstab-check[136]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 2.379079] systemd[1]: Reload requested from client PID 136 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 2.380551] systemd[1]: Reloading... machine # [ 2.448381] systemd[1]: Reloading finished in 69 ms. machine # [ 2.457615] systemd-sysroot-fstab-check[136]: Requesting initrd-fs.target/start/replace... machine # [ 2.460142] systemd-sysroot-fstab-check[136]: Requesting swap.target/start/replace... machine # [ 2.465218] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 2.466600] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 2.467766] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 3.170173] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.176166] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 3.183925] systemd[1]: Mounting /sysroot/run... machine # [ 3.194305] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.207156] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.243919] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.249601] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.251261] systemd[1]: Mounted /sysroot/run. machine # [ 3.253297] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.255396] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.259121] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.269355] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.270784] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.169918] systemd[1]: Mounting /sysroot/nix/store... machine # [ 4.218266] systemd[1]: Mounted /sysroot/nix/store. machine # [ 4.219832] systemd[1]: Reached target Initrd File Systems. machine # [ 4.222080] systemd[1]: Starting Find NixOS closure... machine # [ 4.224510] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 4.244248] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 4.245863] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. machine # [ 4.253692] systemd[1]: Finished Find NixOS closure. machine # [ 4.254965] systemd[1]: Reached target Initrd Default Target. machine # [ 4.256845] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 4.270331] systemd[1]: Stopped target Initrd Default Target. machine # [ 4.271430] systemd[1]: Stopped target Basic System. machine # [ 4.272288] systemd[1]: Stopped target Initrd Root Device. machine # [ 4.273229] systemd[1]: Stopped target Path Units. machine # [ 4.274129] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 4.276237] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 4.277683] systemd[1]: Stopped target Slice Units. machine # [ 4.279132] systemd[1]: Stopped target Socket Units. machine # [ 4.279833] systemd[1]: Stopped target System Initialization. machine # [ 4.280759] systemd[1]: Stopped target Swaps. machine # [ 4.281417] systemd[1]: Stopped target Timer Units. machine # [ 4.282426] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 4.283497] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 4.285134] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 4.286198] systemd[1]: Stopped Find NixOS closure. machine # [ 4.287218] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.289184] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 4.290250] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.291248] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 4.293097] systemd[1]: Stopped Load Kernel Modules. machine # [ 4.293911] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 4.295508] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 4.296877] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 4.297869] systemd[1]: Stopped Create System Files and Directories. machine # [ 4.300129] systemd[1]: Stopped target Local File Systems. machine # [ 4.300850] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 4.302621] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 4.303604] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 4.306237] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 4.307370] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.308392] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.313068] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 4.318144] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 4.319228] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 4.322068] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 4.327348] systemd[1]: Starting Cleanup udev Database... machine # [ 4.328220] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 4.329414] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 4.332031] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 4.333243] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 4.334221] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 4.335169] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 4.336025] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 4.338630] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.353146] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 4.354663] systemd[1]: Finished Cleanup udev Database. machine # [ 4.356133] systemd[1]: Reached target Switch Root. machine # [ 4.357244] systemd[1]: Starting NixOS Activation... machine # [ 4.425946] initrd-nixos-activation-start[187]: booting system configuration /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test machine # [ 4.452330] initrd-nixos-activation-start[187]: running activation script... machine # [ 4.638585] initrd-nixos-activation-start[210]: setting up /etc... machine # [ 4.742857] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 4.745074] systemd[1]: Finished NixOS Activation. machine # [ 4.746320] systemd[1]: Starting Switch Root... machine # [ 4.760132] systemd[1]: Switching root. machine # [ 5.030062] systemd-journald[65]: Received SIGTERM from PID 1 (systemd). machine # [ 5.092019] NET: Registered PF_VSOCK protocol family machine # [ 5.116252] systemd[1]: systemd 263-7f4df15 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 +EXPERIMENTAL-ENVVAR) machine # [ 5.120304] systemd[1]: Detected virtualization kvm. machine # [ 5.121029] systemd[1]: Detected architecture x86-64. machine # [ 5.121728] systemd[1]: Detected first boot. machine # [ 5.123463] systemd[1]: Initializing machine ID from random generator. machine # [ 5.210329] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 5.327414] systemd[1]: Applying preset policy. machine # [ 5.481636] systemd[1]: Populated /etc with preset unit settings. machine # [ 5.630289] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 5.631643] systemd[1]: Stopped initrd-switch-root.service. machine # [ 5.634299] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 5.636396] systemd[1]: Created slice Slice /system/getty. machine # [ 5.637768] systemd[1]: Created slice User and Session Slice. machine # [ 5.638671] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 5.639837] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 5.641506] systemd[1]: Expecting device /dev/hvc0... machine # [ 5.642347] systemd[1]: Expecting device /dev/ttyS0... machine # [ 5.643164] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 5.644019] systemd[1]: Stopped target initrd-fs.target. machine # [ 5.644724] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 5.645528] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 5.646392] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 5.647330] systemd[1]: Reached target Path Units. machine # [ 5.648068] systemd[1]: Reached target Remote File Systems. machine # [ 5.648850] systemd[1]: Reached target Slice Units. machine # [ 5.649556] systemd[1]: Reached target Swaps. machine # [ 5.651530] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 5.665809] systemd[1]: Listening on Process Core Dump Socket. machine # [ 5.667499] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 5.670097] systemd[1]: Listening on Factory Reset Management. machine # [ 5.671060] systemd[1]: Listening on Hostname Service Socket. machine # [ 5.674232] systemd[1]: Starting Journal Log Access Socket... machine # [ 5.675401] systemd[1]: Listening on Journal Audit Socket. machine # [ 5.677880] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 5.679341] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 5.680473] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 5.681803] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 5.685539] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 5.686625] systemd[1]: Listening on udev Varlink Socket. machine # [ 5.689121] systemd[1]: Mounting Huge Pages File System... machine # [ 5.691522] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 5.698024] systemd[1]: Mounting Kernel Debug File System... machine # [ 5.705695] systemd[1]: Mounting Kernel Trace File System... machine # [ 5.713869] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 5.723507] systemd[1]: Starting Load Kernel Module configfs... machine # [ 5.729410] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 5.741116] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 5.749353] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 5.763167] systemd[1]: Mounting FUSE Control File System... machine # [ 5.770190] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 5.786441] systemd[1]: Starting Journal Service... machine # [ 5.794184] systemd[1]: Starting Load Kernel Modules... machine # [ 5.802078] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 5.812477] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 5.819063] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 5.831388] systemd[1]: Starting Coldplug All udev Devices... machine # [ 5.842829] systemd[1]: Listening on Journal Log Access Socket. machine # [ 5.846329] systemd[1]: Mounted Huge Pages File System. machine # [ 5.849511] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 5.851748] systemd-journald[281]: Collecting audit messages is enabled. machine # [ 5.853314] systemd[1]: Mounted Kernel Debug File System. machine # [ 5.859162] systemd[1]: Mounted Kernel Trace File System. machine # [ 5.869133] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 5.729577] systemd[1]: Queued start job for default target Multi-User System. machine # [ 5.876275] systemd[1]: Started Journal Service. machine # [ 5.732399] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0. machine # [ 5.733730] systemd[1]: Unnecessary job was removed for /sys/devices/pnp0/00:03/00:03:0/00:03:0.0/tty/ttyS0. machine # [ 5.736307] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 5.740590] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 5.890188] loop: module loaded machine # [ 5.746397] systemd[1]: Finished Load Kernel Module configfs. machine # [ 5.747524] systemd[1]: Mounted FUSE Control File System. machine # [ 5.752895] systemd-modules-load[282]: Inserted module 'loop' machine # [ 5.907102] EXT4-fs (vda): re-mounted 41960d63-b1ab-442a-a853-a01ca50c13b1. machine # [ 5.765024] systemd[1]: Finished Load Kernel Modules. machine # [ 5.774537] systemd[1]: Mounting Kernel Configuration File System... machine # [ 5.779951] systemd[1]: Starting Firewall... machine # [ 5.784073] systemd[1]: Starting Apply Kernel Variables... machine # [ 5.795296] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 5.801099] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 5.801992] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 5.814605] systemd-oomd[283]: No swap; memory pressure usage will be degraded machine # [ 5.828244] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 5.829228] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 5.837398] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 5.838344] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 5.844256] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 6.046671] systemd-journald[281]: Received client request to flush runtime journal. machine # [ 5.989153] systemd[1]: Mounted Kernel Configuration File System. machine # [ 5.990702] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 5.992135] systemd[1]: Reached target First Boot Complete. machine # [ 5.993944] systemd[1]: Finished Apply Kernel Variables. machine # [ 5.996105] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 6.008197] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 6.011202] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 6.105873] systemd[1]: Finished Coldplug All udev Devices. machine # [ 6.116994] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 6.118319] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 6.121787] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 6.166205] systemd-udevd[322]: Using default interface naming scheme 'v261'. machine # [ 6.208583] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 6.272393] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 6.344698] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 6.367165] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped. machine # [ 6.396989] (udev-worker)[340]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 6.399270] (udev-worker)[340]: Network interface NamePolicy= disabled on kernel command line. machine # [ 6.483349] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 6.486057] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 6.491107] systemd[1]: Mounting /run/wrappers... machine # [ 6.491765] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 6.494287] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 6.496188] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 6.497592] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.499026] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.534975] systemd[1]: Mounted /run/wrappers. machine # [ 6.535713] systemd[1]: Reached target Local File Systems. machine # [ 6.539218] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 6.544157] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 6.545143] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 6.551486] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 6.554900] systemd[1]: Starting Create System Files and Directories... machine # [ 6.672215] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 6.681821] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 6.693766] systemd[1]: Finished Create System Files and Directories. machine # [ 6.841046] mousedev: PS/2 mouse device common for all mice machine # [ 6.701234] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 6.708134] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 6.894483] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 machine # [ 6.784615] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 6.806126] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 6.813338] systemd[1]: Starting Update is Completed... machine # [ 6.968985] ACPI: button: Power Button [PWRF] machine # [ 6.859031] systemd[1]: Finished Update is Completed. machine # [ 7.066804] rtc_cmos PNP0B00:00: RTC can wake from S4 machine # [ 7.070612] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console machine # [ 7.040608] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 7.043138] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 7.045129] systemd[1]: Reached target System Initialization. machine # [ 7.047069] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 7.048130] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 7.050517] systemd[1]: Reached target Timer Units. machine # [ 7.051915] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 7.053277] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 7.055258] systemd[1]: Reached target Socket Units. machine # [ 7.056484] systemd[1]: Reached target Basic System. machine # [ 7.058244] systemd[1]: Started backdoor.service. machine # [ 7.064125] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 7.070444] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 7.082047] systemd[1]: Starting Post-Boot Actions... machine # [ 7.095584] systemd[1]: Started Reset console on configuration changes. machine # [ 7.102544] systemd[1]: Starting resolvconf update... machine # [ 7.114949] systemd[1]: Finished Firewall. machine # [ 7.146919] systemd[1]: Starting D-Bus System Message Bus... machine # [ 7.183085] nsncd[492]: Oct 19 03:00:18.737 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 7.185768] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 7.189973] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 7.192859] systemd[1]: Reached target User and Group Name Lookups. machine # [ 7.214307] systemd[1]: Starting User Login Management... machine # connecting to host... machine # [ 7.234968] systemd[1]: Finished Post-Boot Actions. machine: Guest shell says: b'Spawning backdoor root shell...\n' machine: connected to guest root shell machine # [ 7.266476] systemd[1]: Finished Import lastlog data into lastlog2 database. machine: (connecting took 7.95 seconds) machine: (finished: waiting for the VM to finish booting, in 8.26 seconds) machine # [ 7.089056] rtc_cmos PNP0B00:00: registered as rtc0 machine # [ 7.089128] rtc_cmos PNP0B00:00: setting system clock to 2026-10-19T03:00:18 UTC (1792378818) machine # [ 7.314199] dbus-broker-launch[498]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 7.321124] dbus-broker-launch[498]: NSS returned no entry for 'systemd-timesync' machine # [ 7.322174] dbus-broker-launch[498]: Invalid user-name in /nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 7.089209] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs machine # [ 7.090189] systemd-journald[281]: Time jumped backwards, rotating. machine # [ 7.118024] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input4 machine # [ 7.119407] parport_pc 00:02: reported by Plug and Play ACPI machine # [ 7.119491] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] machine # [ 7.163085] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized machine # [ 7.373865] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt machine # [ 7.374027] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD machine # [ 7.450155] Console: switching to colour dummy device 80x25 machine # [ 7.483894] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6 machine # [ 7.484274] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5 machine # [ 7.604200] [drm] Found bochs VGA, ID 0xb0c5. machine # [ 7.604203] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. machine # [ 7.404388] dbus-broker-launch[498]: Ready machine # [ 7.461928] systemd-logind[513]: New seat seat0. machine # [ 7.463961] systemd-logind[513]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) machine # [ 7.466569] systemd-logind[513]: Watching system buttons on /dev/input/event2 (Power Button) machine # [ 7.615768] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic machine # [ 7.616478] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0 machine # [ 7.488438] systemd[1]: Started D-Bus System Message Bus. machine # [ 7.490432] systemd[1]: Started User Login Management. machine # [ 7.652219] ppdev: user-space parallel port driver machine # [ 7.521516] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 7.522443] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 7.523338] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 7.524317] systemd[1]: Stopping User and Group Name Lookups... machine # [ 7.532427] systemd[1]: Starting linger-users.service... machine # [ 7.534693] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 7.547410] systemd[1]: Starting Virtual Console Setup... machine # [ 7.557094] systemd[1]: nscd.service: Deactivated successfully. machine # [ 7.557883] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 7.568224] systemd-logind[513]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 7.607535] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 7.617060] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 7.619661] systemd[1]: Stopped Virtual Console Setup. machine # [ 7.642418] systemd[1]: Finished resolvconf update. machine # [ 7.652094] systemd[1]: Reached target Preparation for Network. machine # [ 7.664256] systemd[1]: Starting DHCP Client... machine # [ 7.668165] systemd[1]: Starting Address configuration of eth1... machine # [ 7.674098] systemd[1]: Starting Extra networking commands.... machine # [ 7.689237] nsncd[590]: Oct 19 03:00:19.242 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 7.695081] systemd[1]: Starting Virtual Console Setup... machine # [ 7.698893] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 7.854569] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660) machine # [ 7.859169] Console: switching to colour frame buffer device 160x50 machine # [ 7.767757] systemd[1]: Created slice Slice /user/1001. machine # [ 7.768693] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 7.872441] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0) machine # [ 7.916552] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device machine # [ 7.772609] systemd[1]: Reached target User and Group Name Lookups. machine # [ 7.775474] systemd[1]: Starting User Runtime Directory /run/user/1001... machine # [ 7.787939] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 7.788933] systemd[1]: Finished linger-users.service. machine # [ 7.813084] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 7.814297] systemd[1]: Stopped Virtual Console Setup. machine # [ 7.820302] systemd[1]: Starting Virtual Console Setup... machine # [ 7.860294] systemd[1]: Finished User Runtime Directory /run/user/1001. machine # [ 7.866290] systemd[1]: Starting User Manager for UID 1001... machine # [ 7.913511] (systemd)[628]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 7.928392] network-addresses-eth1-start[599]: adding address 192.168.1.1/24... done machine # [ 7.931571] systemd-logind[513]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'. machine # [ 7.964664] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 8.033587] network-addresses-eth1-start[599]: adding address 2001:db8:1::1/64... done machine # [ 8.037382] systemd[1]: Finished Address configuration of eth1. machine # [ 8.111344] dhcpcd[619]: dhcpcd-10.3.2 starting machine # [ 8.272773] kvm_amd: TSC scaling supported machine # [ 8.273201] kvm_amd: Nested Virtualization enabled machine # [ 8.273638] kvm_amd: Nested Paging enabled machine # [ 8.277051] kvm_amd: LBR virtualization supported machine # [ 8.277512] kvm_amd: Virtual VMLOAD VMSAVE supported machine # [ 8.133867] dhcpcd[684]: dev: loaded udev machine # [ 8.280612] kvm_amd: Virtual GIF supported machine # [ 8.281315] kvm_amd: Virtual NMI enabled machine # [ 8.302823] 8021q: 802.1Q VLAN Support v1.8 machine # [ 8.304318] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 8.193152] systemd[1]: Finished Extra networking commands.. machine # [ 8.195063] systemd[1]: Reached target Network. machine # [ 8.200244] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 8.351459] EDAC MC: Ver: 3.0.0 machine # [ 8.213123] systemd[1]: Starting Permit User Sessions... machine # [ 8.262980] systemd[1]: Finished Permit User Sessions. machine # [ 8.269127] systemd[1]: Started Getty on tty1. machine # [ 8.273173] systemd[1]: Reached target Login Prompts. machine # [ 8.439744] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 8.306143] systemd[628]: Queued start job for default target Main User Target. machine # [ 8.309891] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 8.313704] systemd[628]: Created slice User Application Slice. machine # [ 8.314914] systemd[628]: Started Daily Cleanup of User's Temporary Directories. machine # [ 8.315987] systemd[628]: Reached target Paths. machine # [ 8.462131] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 8.462775] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 8.318593] systemd[628]: Reached target Timers. machine # [ 8.322367] systemd[628]: Starting D-Bus User Message Bus Socket... machine # [ 8.468331] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 8.469193] cfg80211: failed to load regulatory.db machine # [ 8.327680] systemd[628]: Starting Create User Files and Directories... machine # [ 8.372855] systemd[628]: Finished Create User Files and Directories. machine # [ 8.534617] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 8.390914] dhcpcd[684]: eth0: waiting for carrier machine # [ 8.391983] dhcpcd[684]: eth0: carrier acquired machine # [ 8.394989] systemd[628]: Listening on D-Bus User Message Bus Socket. machine # [ 8.398952] systemd[628]: Reached target Sockets. machine # [ 8.401379] systemd[628]: Reached target Basic System. machine # [ 8.402575] systemd[1]: Started User Manager for UID 1001. machine # [ 8.405405] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 8.410268] dhcpcd[684]: DUID 00:01:00:01:32:68:44:43:52:54:00:12:34:56 machine # [ 8.411842] dhcpcd[684]: eth0: IAID 00:12:34:56 machine # [ 8.412789] dhcpcd[684]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 8.430129] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 8.431366] systemd[628]: Reached target Main User Target. machine # [ 8.432969] systemd[628]: Startup finished in 479ms. machine # [ 8.456166] systemd-vconsole-setup[621]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 8.460390] systemd[1]: Finished Virtual Console Setup. machine # [ 9.528784] dhcpcd[684]: eth0: soliciting a DHCP lease machine # [ 9.691047] NET: Registered PF_PACKET protocol family machine # [ 9.552982] dhcpcd[684]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 9.555416] dhcpcd[684]: eth0: probing address 10.0.2.15/24 machine # [ 10.135558] dhcpcd[684]: eth0: soliciting an IPv6 router machine # [ 10.138453] dhcpcd[684]: eth0: Router Advertisement from fe80::2 machine # [ 10.140636] dhcpcd[684]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 10.143635] dhcpcd[684]: eth0: adding route to fec0::/64 machine # [ 10.145724] dhcpcd[684]: eth0: adding default route via fe80::2 machine # [ 14.594763] dhcpcd[684]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 14.597801] dhcpcd[684]: eth0: adding route to 10.0.2.0/24 machine # [ 14.600462] dhcpcd[684]: eth0: adding default route via 10.0.2.2 machine # [ 14.701241] systemd[1]: Started DHCP Client. machine # [ 14.705180] systemd[1]: Reached target Multi-User System. machine # [ 14.705966] systemd[1]: Startup finished in 887ms (kernel) + 4.037s (initrd) + 9.780s (userspace) = 14.704s. machine: (finished: waiting for unit multi-user.target, in 16.03 seconds) machine: must succeed: /nix/store/3vbcjh6yz1i6lp352svzp92f3jmjppg7-stderr-runner /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/bin/switch-to-configuration test machine # Checking switch inhibitors... done machine # [ 15.247793] nixos[840]: switching to system configuration /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test machine # activating the configuration... machine # [ 15.251597] systemd[1]: Stopped target Remote File Systems. machine # [ 15.252470] systemd[1]: Stopped target Local File Systems. machine # setting up /etc... machine # [ 15.478346] systemd[1]: Reload requested from client PID 840 ('.switch-to-conf') (unit backdoor.service)... machine # [ 15.479890] systemd[1]: Reloading... machine # [ 15.740909] systemd[1]: Reloading finished in 258 ms. machine # reloading user units for usertest... machine # [ 15.769418] systemd[628]: Created slice User Core Session Slice. machine # [ 15.771720] systemd[628]: Starting D-Bus User Message Bus... machine # [ 15.795190] dbus-broker-launch[910]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/services/org.freedesktop.import1.service' machine # [ 15.797963] dbus-broker-launch[910]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/services/org.freedesktop.machine1.service' machine # [ 15.800633] dbus-broker-launch[910]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/services/org.freedesktop.portable1.service' machine # [ 15.803328] dbus-broker-launch[910]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/services/org.freedesktop.systemd1.service' machine # [ 15.806059] dbus-broker-launch[910]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored machine # [ 15.808139] dbus-broker-launch[910]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored machine # [ 15.809818] systemd[628]: Started D-Bus User Message Bus. machine # [ 15.813550] dbus-broker-launch[910]: Ready machine # [ 15.855994] systemd[628]: Reexecution requested from client PID 909 ('switch-to-confi')... machine # [ 15.857344] systemd[628]: Reexecuting. machine # [ 15.876971] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 15.952435] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 15.957865] systemd[628]: Reload requested from client PID 909 ('switch-to-confi')... machine # [ 15.959912] systemd[628]: Reloading... machine # [ 16.029056] systemd[628]: Reloading finished in 69 ms. machine # [ 16.034829] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 16.042093] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 16.043065] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 16.045227] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 16.064189] systemd[628]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 16.073560] systemd[1]: Starting Re-setup tmpfiles on a system that is already running.... machine # [ 16.140661] systemd[1]: Finished Re-setup tmpfiles on a system that is already running.. machine # [ 16.142623] systemd[1]: Reached target Reactivate sysinit units. machine # [ 16.147987] systemd[1]: Reached target Remote File Systems. machine # [ 16.153560] systemd[1]: Starting linger-users.service... machine # [ 16.156943] systemd[1]: Reached target Local File Systems. machine # [ 16.159371] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 16.162386] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 16.163665] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 16.164812] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 16.166967] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 16.168455] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 16.173362] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 16.177798] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 16.180241] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 16.211249] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 16.212832] systemd[1]: Finished linger-users.service. machine # [ 16.325807] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 16.327437] systemd[1]: Finished Create SUID/SGID Wrappers. machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service machine # [ 16.588944] nixos[840]: finished switching to system configuration /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3vbcjh6yz1i6lp352svzp92f3jmjppg7-stderr-runner /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/bin/switch-to-configuration test, in 1.56 seconds) machine: must succeed: rm /etc/NIXOS machine: (finished: must succeed: rm /etc/NIXOS, in 0.03 seconds) machine: must succeed: /nix/store/3vbcjh6yz1i6lp352svzp92f3jmjppg7-stderr-runner /nix/store/cssmzw3iavz1v1impl73lvs2bann39bm-nixos-system-other-test/bin/switch-to-configuration test machine # this should succeed (config: /nix/store/cssmzw3iavz1v1impl73lvs2bann39bm-nixos-system-other-test, action: test) machine # Checking switch inhibitors... done machine # [ 16.855490] nixos[987]: switching to system configuration /nix/store/cssmzw3iavz1v1impl73lvs2bann39bm-nixos-system-other-test machine # activating the configuration... machine # [ 16.859638] systemd[1]: Stopped target Local File Systems. machine # [ 16.860697] systemd[1]: Stopped target Remote File Systems. machine # removing user ‘usertest’ machine # setting up /etc... machine # [ 17.080832] systemd[1]: Reload requested from client PID 987 ('.switch-to-conf') (unit backdoor.service)... machine # [ 17.082324] systemd[1]: Reloading... machine # [ 17.379915] systemd[1]: Reloading finished in 295 ms. machine # reloading user units for usertest... machine # [ 17.443984] systemd[628]: Reexecution requested from client PID 1058 ('switch-to-confi')... machine # [ 17.446708] systemd[628]: Reexecuting. machine # [ 17.467060] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 17.545939] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 17.551939] systemd[628]: Reload requested from client PID 1058 ('switch-to-confi')... machine # [ 17.553158] systemd[628]: Reloading... machine # [ 17.626973] systemd[628]: Reloading finished in 72 ms. machine # [ 17.632888] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 17.640062] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 17.641914] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 17.643504] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 17.662448] systemd[628]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 17.665994] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 17.667486] systemd[1]: Stopping Reactivate sysinit units... machine # [ 17.668491] systemd[1]: Reached target Reactivate sysinit units. machine # reloading the following units: dbus-broker.service machine # [ 17.671701] dbus-broker-launch[498]: Caught SIGHUP, trigger reload. machine # [ 17.673188] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 17.678356] dbus-broker-launch[498]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 17.680226] dbus-broker-launch[498]: NSS returned no entry for 'systemd-timesync' machine # [ 17.681251] dbus-broker-launch[498]: Invalid user-name in /nix/store/2h842v43dq0mx3dmyabal4yr4l01qpk4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 17.691393] systemd[1]: Reloaded D-Bus System Message Bus. machine # restarting the following units: network-addresses-eth1.service machine # [ 17.696282] systemd[1]: Stopping Address configuration of eth1... machine # [ 17.717968] network-addresses-eth1-pre-stop[1070]: deleting address 192.168.1.1/24... done machine # [ 17.723057] network-addresses-eth1-pre-stop[1070]: deleting address 2001:db8:1::1/64... done machine # [ 17.727927] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 17.729451] systemd[1]: Stopped Address configuration of eth1. machine # [ 17.732679] systemd[1]: Starting Address configuration of eth1... machine # [ 17.762357] network-addresses-eth1-start[1075]: adding address 192.168.1.2/24... done machine # [ 17.767469] network-addresses-eth1-start[1075]: adding address 2001:db8:1::2/64... done machine # [ 17.775254] systemd[1]: Finished Address configuration of eth1. machine # [ 17.778640] systemd[1]: Reached target Remote File Systems. machine # [ 17.783067] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 17.785293] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 17.787261] systemd[1]: Reached target Local File Systems. machine # [ 17.791226] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 17.794771] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 17.796345] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 17.798941] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 17.801190] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 17.802840] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 17.806396] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 17.975742] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 17.978269] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 17.981379] systemd[1]: Starting linger-users.service... machine # [ 18.000539] linger-users-start[1148]: id: ‘usertest’: no such user machine # [ 18.002303] linger-users-start[1146]: Removing linger for missing user usertest machine # [ 18.006923] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 18.008278] systemd[1]: Finished linger-users.service. machine # [ 18.266336] nixos[987]: finished switching to system configuration /nix/store/cssmzw3iavz1v1impl73lvs2bann39bm-nixos-system-other-test machine: (finished: must succeed: /nix/store/3vbcjh6yz1i6lp352svzp92f3jmjppg7-stderr-runner /nix/store/cssmzw3iavz1v1impl73lvs2bann39bm-nixos-system-other-test/bin/switch-to-configuration test, in 1.65 seconds) subtest: services machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 18.488699] nixos[1154]: switching to system configuration /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test machine # [ 18.491575] systemd[1]: Stopped target Remote File Systems. machine # [ 18.493303] systemd[1]: Stopped target Local File Systems. machine # [ 18.692218] systemd[1]: Reload requested from client PID 1154 ('.switch-to-conf') (unit backdoor.service)... machine # [ 18.693900] systemd[1]: Reloading... machine # [ 18.922860] systemd[1]: Reloading finished in 227 ms. machine # [ 18.982654] systemd[628]: Reexecution requested from client PID 1223 ('switch-to-confi')... machine # [ 18.985244] systemd[628]: Reexecuting. machine # [ 19.006067] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 19.077134] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 19.082298] systemd[628]: Reload requested from client PID 1223 ('switch-to-confi')... machine # [ 19.083503] systemd[628]: Reloading... machine # [ 19.150966] systemd[628]: Reloading finished in 65 ms. machine # [ 19.155812] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 19.161902] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 19.162964] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 19.165482] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 19.185114] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 19.188391] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 19.189821] systemd[1]: Stopping Reactivate sysinit units... machine # [ 19.191247] systemd[1]: Reached target Reactivate sysinit units. machine # [ 19.194491] dbus-broker-launch[498]: Caught SIGHUP, trigger reload. machine # [ 19.195936] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 19.198899] dbus-broker-launch[498]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 19.200750] dbus-broker-launch[498]: NSS returned no entry for 'systemd-timesync' machine # [ 19.201878] dbus-broker-launch[498]: Invalid user-name in /nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 19.210676] systemd[1]: Reloaded D-Bus System Message Bus. machine # [ 19.215140] systemd[1]: Stopping Address configuration of eth1... machine # [ 19.236376] network-addresses-eth1-pre-stop[1235]: deleting address 192.168.1.2/24... done machine # [ 19.241466] network-addresses-eth1-pre-stop[1235]: deleting address 2001:db8:1::2/64... done machine # [ 19.246439] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 19.248083] systemd[1]: Stopped Address configuration of eth1. machine # [ 19.251228] systemd[1]: Starting Address configuration of eth1... machine # [ 19.275570] network-addresses-eth1-start[1240]: adding address 192.168.1.1/24... done machine # [ 19.280465] network-addresses-eth1-start[1240]: adding address 2001:db8:1::1/64... done machine # [ 19.286883] systemd[1]: Finished Address configuration of eth1. machine # [ 19.290174] systemd[1]: Reached target Local File Systems. machine # [ 19.292307] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 19.294571] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 19.296612] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 19.298456] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 19.300438] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 19.301895] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 19.303072] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 19.307293] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 19.308496] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 19.317059] systemd[1]: Reached target Remote File Systems. machine # [ 19.439587] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 19.442146] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 19.445320] systemd[1]: Starting linger-users.service... machine # [ 19.467873] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 19.469383] systemd[1]: Finished linger-users.service. machine # [ 19.725872] nixos[1154]: finished switching to system configuration /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.46 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 19.942262] nixos[1309]: switching to system configuration /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test machine # [ 19.945467] systemd[1]: Stopped target Local File Systems. machine # [ 19.946531] systemd[1]: Stopped target Remote File Systems. machine # [ 20.151858] systemd[1]: Reload requested from client PID 1309 ('.switch-to-conf') (unit backdoor.service)... machine # [ 20.153503] systemd[1]: Reloading... machine # [ 20.383108] systemd[1]: Reloading finished in 227 ms. machine # [ 20.444361] systemd[628]: Reexecution requested from client PID 1378 ('switch-to-confi')... machine # [ 20.445619] systemd[628]: Reexecuting. machine # [ 20.466166] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 20.538912] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 20.544022] systemd[628]: Reload requested from client PID 1378 ('switch-to-confi')... machine # [ 20.545715] systemd[628]: Reloading... machine # [ 20.616951] systemd[628]: Reloading finished in 70 ms. machine # [ 20.622877] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 20.629256] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 20.631079] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 20.632544] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 20.651264] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 20.654289] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 20.655523] systemd[1]: Stopping Reactivate sysinit units... machine # [ 20.656544] systemd[1]: Reached target Reactivate sysinit units. machine # [ 20.659075] systemd[1]: Reached target Remote File Systems. machine # [ 20.662518] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 20.664104] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 20.669234] systemd[1]: Starting linger-users.service... machine # [ 20.672057] systemd[1]: Reached target Local File Systems. machine # [ 20.674196] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 20.678054] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 20.678795] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 20.681531] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 20.683489] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 20.687170] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 20.688337] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 20.726745] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 20.728753] systemd[1]: Finished linger-users.service. machine # [ 20.832072] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 20.833667] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 21.089656] nixos[1309]: finished switching to system configuration /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.36 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1 machine # [ 21.303183] nixos[1449]: switching to system configuration /nix/store/3qsagb16j4nxwkakzq19znhdaq9appy0-nixos-system-machine-test machine # [ 21.305455] systemd[1]: Stopped target Local File Systems. machine # [ 21.306950] systemd[1]: Stopped target Remote File Systems. machine # [ 21.510409] systemd[1]: Reload requested from client PID 1449 ('.switch-to-conf') (unit backdoor.service)... machine # [ 21.512117] systemd[1]: Reloading... machine # [ 21.745916] systemd[1]: Reloading finished in 232 ms. machine # [ 21.809709] systemd[628]: Reexecution requested from client PID 1519 ('switch-to-confi')... machine # [ 21.811554] systemd[628]: Reexecuting. machine # [ 21.830053] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 21.899786] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 21.905818] systemd[628]: Reload requested from client PID 1519 ('switch-to-confi')... machine # [ 21.908060] systemd[628]: Reloading... machine # [ 21.980516] systemd[628]: Reloading finished in 72 ms. machine # [ 21.985877] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 21.991842] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 21.992834] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 21.995319] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 22.014132] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 22.017209] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 22.018781] systemd[1]: Stopping Reactivate sysinit units... machine # [ 22.019804] systemd[1]: Reached target Reactivate sysinit units. machine # [ 22.021792] systemd[1]: Reached target Remote File Systems. machine # [ 22.025072] systemd[1]: Reached target Local File Systems. machine # [ 22.027470] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 22.030830] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 22.031861] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 22.033311] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 22.035250] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 22.036809] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.037984] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.048334] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.049699] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 22.179098] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 22.180686] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 22.185335] systemd[1]: Starting linger-users.service... machine # [ 22.187068] systemd[1]: Starting test.service... machine # [ 22.210302] systemd[1]: Finished test.service. machine # [ 22.224234] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 22.225661] systemd[1]: Finished linger-users.service. machine # [ 22.483529] nixos[1449]: finished switching to system configuration /nix/store/3qsagb16j4nxwkakzq19znhdaq9appy0-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1, in 1.40 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1 machine # [ 22.700845] nixos[1592]: switching to system configuration /nix/store/3qsagb16j4nxwkakzq19znhdaq9appy0-nixos-system-machine-test machine # [ 22.703206] systemd[1]: Stopped target Local File Systems. machine # [ 22.705077] systemd[1]: Stopped target Remote File Systems. machine # [ 22.909574] systemd[1]: Reload requested from client PID 1592 ('.switch-to-conf') (unit backdoor.service)... machine # [ 22.911414] systemd[1]: Reloading... machine # [ 23.147068] systemd[1]: Reloading finished in 234 ms. machine # [ 23.207345] systemd[628]: Reexecution requested from client PID 1661 ('switch-to-confi')... machine # [ 23.209219] systemd[628]: Reexecuting. machine # [ 23.230329] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.301821] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.307076] systemd[628]: Reload requested from client PID 1661 ('switch-to-confi')... machine # [ 23.308542] systemd[628]: Reloading... machine # [ 23.384909] systemd[628]: Reloading finished in 75 ms. machine # [ 23.389880] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.396216] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 23.398129] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 23.399857] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 23.420110] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 23.423233] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 23.424677] systemd[1]: Stopping Reactivate sysinit units... machine # [ 23.425679] systemd[1]: Reached target Reactivate sysinit units. machine # [ 23.429587] systemd[1]: Reached target Remote File Systems. machine # [ 23.432272] systemd[1]: Starting linger-users.service... machine # [ 23.437700] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 23.438977] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 23.443174] systemd[1]: Reached target Local File Systems. machine # [ 23.447301] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 23.451669] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 23.452513] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 23.453633] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 23.455549] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 23.456966] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 23.458258] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 23.495387] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 23.497614] systemd[1]: Finished linger-users.service. machine # [ 23.604766] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 23.607808] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 23.865468] nixos[1592]: finished switching to system configuration /nix/store/3qsagb16j4nxwkakzq19znhdaq9appy0-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1, in 1.38 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceDifferentDescription/bin/switch-to-configuration test 2>&1 machine # [ 24.095351] nixos[1732]: switching to system configuration /nix/store/9jn0bmbbhw6izbzgshcypjl73ypf7kwb-nixos-system-machine-test machine # [ 24.097899] systemd[1]: Stopped target Remote File Systems. machine # [ 24.099537] systemd[1]: Stopped target Local File Systems. machine # [ 24.351519] systemd[1]: Reload requested from client PID 1732 ('.switch-to-conf') (unit backdoor.service)... machine # [ 24.353184] systemd[1]: Reloading... machine # [ 24.618882] systemd[1]: Reloading finished in 263 ms. machine # [ 24.679373] systemd[628]: Reexecution requested from client PID 1802 ('switch-to-confi')... machine # [ 24.681989] systemd[628]: Reexecuting. machine # [ 24.702203] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 24.774473] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 24.779889] systemd[628]: Reload requested from client PID 1802 ('switch-to-confi')... machine # [ 24.781878] systemd[628]: Reloading... machine # [ 24.852580] systemd[628]: Reloading finished in 71 ms. machine # [ 24.857843] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 24.864794] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 24.865691] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 24.867223] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 24.886291] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 24.889503] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 24.890557] systemd[1]: Stopping Reactivate sysinit units... machine # [ 24.891796] systemd[1]: Reached target Reactivate sysinit units. machine # [ 24.894407] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.896307] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 24.899828] systemd[1]: Reached target Remote File Systems. machine # [ 24.905409] systemd[1]: Reached target Local File Systems. machine # [ 24.908145] systemd[1]: Starting linger-users.service... machine # [ 24.913798] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 24.916932] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 24.917709] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 24.918870] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 24.921219] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 24.922632] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.923831] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.962701] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 24.964577] systemd[1]: Finished linger-users.service. machine # [ 25.069381] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 25.071079] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 25.327793] nixos[1732]: finished switching to system configuration /nix/store/9jn0bmbbhw6izbzgshcypjl73ypf7kwb-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceDifferentDescription/bin/switch-to-configuration test 2>&1, in 1.46 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1 machine # [ 25.540266] nixos[1873]: switching to system configuration /nix/store/gpjbpszcj0argzwqlwxdij9bimgvkxv8-nixos-system-machine-test machine # [ 25.542467] systemd[1]: Stopped target Local File Systems. machine # [ 25.544468] systemd[1]: Stopped target Remote File Systems. machine # [ 25.545604] systemd[1]: test.service: Deactivated successfully. machine # [ 25.546875] systemd[1]: Stopped Test unit. machine # [ 25.771293] systemd[1]: Reload requested from client PID 1873 ('.switch-to-conf') (unit backdoor.service)... machine # [ 25.772965] systemd[1]: Reloading... machine # [ 25.995914] systemd[1]: Reloading finished in 221 ms. machine # [ 26.054482] systemd[628]: Reexecution requested from client PID 1942 ('switch-to-confi')... machine # [ 26.056899] systemd[628]: Reexecuting. machine # [ 26.076780] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 26.144606] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 26.149778] systemd[628]: Reload requested from client PID 1942 ('switch-to-confi')... machine # [ 26.150990] systemd[628]: Reloading... machine # [ 26.222388] systemd[628]: Reloading finished in 69 ms. machine # [ 26.226823] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 26.233233] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 26.234116] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 26.237263] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 26.258966] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 26.261926] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 26.263193] systemd[1]: Stopping Reactivate sysinit units... machine # [ 26.263949] systemd[1]: Reached target Reactivate sysinit units. machine # [ 26.267628] systemd[1]: Reached target Local File Systems. machine # [ 26.269251] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.270395] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 26.272320] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 26.275215] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 26.275975] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 26.278102] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 26.280030] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 26.281420] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.282544] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.288977] systemd[1]: Reached target Remote File Systems. machine # [ 26.418205] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 26.419810] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 26.423760] systemd[1]: Starting linger-users.service... machine # [ 26.426425] systemd[1]: Starting test.service... machine # [ 26.449900] systemd[1]: Finished test.service. machine # [ 26.463483] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 26.465481] systemd[1]: Finished linger-users.service. machine # [ 26.722607] nixos[1873]: finished switching to system configuration /nix/store/gpjbpszcj0argzwqlwxdij9bimgvkxv8-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1, in 1.39 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 26.950342] nixos[2015]: switching to system configuration /nix/store/hrg0892sd5m6szy0q1g0vjwv0ashwlyw-nixos-system-machine-test machine # [ 26.952611] systemd[1]: Stopped target Local File Systems. machine # [ 26.954478] systemd[1]: Stopped target Remote File Systems. machine # [ 27.184094] systemd[1]: Reload requested from client PID 2015 ('.switch-to-conf') (unit backdoor.service)... machine # [ 27.185761] systemd[1]: Reloading... machine # [ 27.428757] systemd[1]: Reloading finished in 241 ms. machine # [ 27.503287] systemd[628]: Reexecution requested from client PID 2084 ('switch-to-confi')... machine # [ 27.505661] systemd[628]: Reexecuting. machine # [ 27.526404] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.597402] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.602600] systemd[628]: Reload requested from client PID 2084 ('switch-to-confi')... machine # [ 27.604870] systemd[628]: Reloading... machine # [ 27.672799] systemd[628]: Reloading finished in 68 ms. machine # [ 27.678816] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.685191] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 27.686088] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 27.688276] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 27.708428] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 27.711429] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 27.712792] systemd[1]: Stopping Reactivate sysinit units... machine # [ 27.713804] systemd[1]: Reached target Reactivate sysinit units. machine # [ 27.715497] systemd[1]: test.service: Deactivated successfully. machine # [ 27.717281] systemd[1]: Stopped test.service. machine # [ 27.717903] systemd[1]: Stopping test.service... machine # [ 27.719558] systemd[1]: Starting test.service... machine # [ 27.734644] systemd[1]: Finished test.service. machine # [ 27.737915] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.739653] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 27.741691] systemd[1]: Reached target Local File Systems. machine # [ 27.744929] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 27.749429] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 27.750377] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 27.752103] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 27.754154] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 27.755621] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.756854] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.764834] systemd[1]: Reached target Remote File Systems. machine # [ 27.887793] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 27.889413] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 27.893455] systemd[1]: Starting linger-users.service... machine # [ 27.920438] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 27.921877] systemd[1]: Finished linger-users.service. machine # [ 28.176697] nixos[2015]: finished switching to system configuration /nix/store/hrg0892sd5m6szy0q1g0vjwv0ashwlyw-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 1.45 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceReload/bin/switch-to-configuration test 2>&1 machine # [ 28.373807] nixos[2158]: switching to system configuration /nix/store/1s7vsiiz9qilpgqxnrqvddlbxlfm8zdc-nixos-system-machine-test machine # [ 28.376280] systemd[1]: Stopped target Remote File Systems. machine # [ 28.378053] systemd[1]: Stopped target Local File Systems. machine # [ 28.607172] systemd[1]: Reload requested from client PID 2158 ('.switch-to-conf') (unit backdoor.service)... machine # [ 28.608881] systemd[1]: Reloading... machine # [ 28.842155] systemd[1]: Reloading finished in 232 ms. machine # [ 28.903810] systemd[628]: Reexecution requested from client PID 2227 ('switch-to-confi')... machine # [ 28.905583] systemd[628]: Reexecuting. machine # [ 28.926400] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 28.996815] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.002668] systemd[628]: Reload requested from client PID 2227 ('switch-to-confi')... machine # [ 29.004371] systemd[628]: Reloading... machine # [ 29.075046] systemd[628]: Reloading finished in 69 ms. machine # [ 29.080838] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.088071] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 29.088928] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 29.091100] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 29.112463] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 29.115423] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 29.116784] systemd[1]: Stopping Reactivate sysinit units... machine # [ 29.117710] systemd[1]: Reached target Reactivate sysinit units. machine # [ 29.120822] systemd[1]: Reloading test.service... machine # [ 29.134459] systemd[1]: Reloaded test.service. machine # [ 29.141891] systemd[1]: Reached target Local File Systems. machine # [ 29.144219] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 29.147061] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 29.147820] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 29.150154] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 29.152270] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 29.155204] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 29.156674] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 29.157810] systemd[1]: Reached target Remote File Systems. machine # [ 29.162335] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 29.163782] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 29.294078] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 29.296089] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 29.299846] systemd[1]: Starting linger-users.service... machine # [ 29.327772] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 29.329376] systemd[1]: Finished linger-users.service. machine # [ 29.586129] nixos[2158]: finished switching to system configuration /nix/store/1s7vsiiz9qilpgqxnrqvddlbxlfm8zdc-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceReload/bin/switch-to-configuration test 2>&1, in 1.41 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1 machine # [ 29.816373] nixos[2300]: switching to system configuration /nix/store/kkqqq6mdswcwxmqdw5gr8ygx0bk8ay5a-nixos-system-machine-test machine # [ 29.820500] systemd[1]: Stopped target Local File Systems. machine # [ 29.821712] systemd[1]: Stopped target Remote File Systems. machine # [ 30.047948] systemd[1]: Reload requested from client PID 2300 ('.switch-to-conf') (unit backdoor.service)... machine # [ 30.049789] systemd[1]: Reloading... machine # [ 30.304199] systemd[1]: Reloading finished in 251 ms. machine # [ 30.370493] systemd[628]: Reexecution requested from client PID 2369 ('switch-to-confi')... machine # [ 30.371935] systemd[628]: Reexecuting. machine # [ 30.392087] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 30.463499] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 30.468808] systemd[628]: Reload requested from client PID 2369 ('switch-to-confi')... machine # [ 30.471145] systemd[628]: Reloading... machine # [ 30.543578] systemd[628]: Reloading finished in 72 ms. machine # [ 30.548849] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 30.555816] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 30.556662] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 30.558225] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 30.577834] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 30.580901] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 30.582238] systemd[1]: Stopping Reactivate sysinit units... machine # [ 30.583339] systemd[1]: Reached target Reactivate sysinit units. machine # [ 30.587610] systemd[1]: Reached target Local File Systems. machine # [ 30.589254] systemd[1]: Reached target Remote File Systems. machine # [ 30.590387] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 30.593057] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 30.593826] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 30.596256] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 30.599023] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 30.600456] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 30.601751] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 30.611767] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 30.613528] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 30.770468] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 30.772113] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 30.776064] systemd[1]: Starting linger-users.service... machine # [ 30.803877] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 30.806172] systemd[1]: Finished linger-users.service. machine # [ 31.060823] nixos[2300]: finished switching to system configuration /nix/store/kkqqq6mdswcwxmqdw5gr8ygx0bk8ay5a-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1, in 1.48 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration dry-activate 2>&1 machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration dry-activate 2>&1, in 0.26 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 31.518592] nixos[2451]: switching to system configuration /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test machine # [ 31.521034] systemd[1]: Stopped target Remote File Systems. machine # [ 31.522537] systemd[1]: Stopped target Local File Systems. machine # [ 31.524238] systemd[1]: test.service: Deactivated successfully. machine # [ 31.525624] systemd[1]: Stopped test.service. machine # [ 31.731483] systemd[1]: Reload requested from client PID 2451 ('.switch-to-conf') (unit backdoor.service)... machine # [ 31.733403] systemd[1]: Reloading... machine # [ 31.965910] systemd[1]: Reloading finished in 231 ms. machine # [ 32.027762] systemd[628]: Reexecution requested from client PID 2520 ('switch-to-confi')... machine # [ 32.029805] systemd[628]: Reexecuting. machine # [ 32.049040] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.119987] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.125711] systemd[628]: Reload requested from client PID 2520 ('switch-to-confi')... machine # [ 32.128097] systemd[628]: Reloading... machine # [ 32.198254] systemd[628]: Reloading finished in 70 ms. machine # [ 32.203828] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.210244] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 32.212064] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 32.213964] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 32.235457] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 32.238891] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 32.240439] systemd[1]: Stopping Reactivate sysinit units... machine # [ 32.241617] systemd[1]: Reached target Reactivate sysinit units. machine # [ 32.245829] systemd[1]: Reached target Remote File Systems. machine # [ 32.250170] systemd[1]: Starting linger-users.service... machine # [ 32.256177] systemd[1]: Reached target Local File Systems. machine # [ 32.256946] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 32.261463] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 32.262377] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 32.263539] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 32.265636] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 32.267170] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 32.268412] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 32.273151] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 32.274430] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 32.319059] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 32.321033] systemd[1]: Finished linger-users.service. machine # [ 32.426264] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 32.427907] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 32.686282] nixos[2451]: finished switching to system configuration /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.36 seconds) machine: must succeed: /nix/store/p43rm4gj2mcbi6psqm9x948amws5a2kd-separate-activation-script/bin/switch-to-configuration test 2>&1 machine # [ 32.897821] nixos[2591]: switching to system configuration /nix/store/1jhy9w89hhbr6b4wyn8d91w2g5h1p8ig-nixos-system-machine-test machine # [ 32.900550] systemd[1]: Stopped target Local File Systems. machine # [ 32.902503] systemd[1]: Stopped target Remote File Systems. machine # [ 33.128413] systemd[1]: Reload requested from client PID 2591 ('.switch-to-conf') (unit backdoor.service)... machine # [ 33.130266] systemd[1]: Reloading... machine # [ 33.374862] systemd[1]: Reloading finished in 242 ms. machine # [ 33.438270] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 33.440407] systemd[628]: Reexecution requested from client PID 2660 ('switch-to-confi')... machine # [ 33.442046] systemd[628]: Reexecuting. machine # [ 33.462286] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.533626] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.539746] systemd[628]: Reload requested from client PID 2660 ('switch-to-confi')... machine # [ 33.541247] systemd[628]: Reloading... machine # [ 33.611543] systemd[628]: Reloading finished in 69 ms. machine # [ 33.617885] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.626024] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 33.627473] systemd[1]: Stopping Reactivate sysinit units... machine # [ 33.628972] systemd[1]: Reached target Reactivate sysinit units. machine # [ 33.631561] systemd[1]: Reached target Remote File Systems. machine # [ 33.635515] systemd[1]: Starting linger-users.service... machine # [ 33.637598] systemd[1]: Starting test.service... machine # [ 33.645307] systemd[1]: Reached target Local File Systems. machine # [ 33.646159] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 33.649446] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 33.650285] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 33.651429] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 33.653442] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 33.654859] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.655982] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.666864] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.668193] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 33.690540] systemd[1]: Finished test.service. machine # [ 33.712536] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 33.714723] systemd[1]: Finished linger-users.service. machine # [ 33.818110] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 33.819687] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 34.078388] nixos[2591]: finished switching to system configuration /nix/store/1jhy9w89hhbr6b4wyn8d91w2g5h1p8ig-nixos-system-machine-test machine: (finished: must succeed: /nix/store/p43rm4gj2mcbi6psqm9x948amws5a2kd-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.02 seconds) machine: must succeed: ! test -e /run/current-system/bin/switch-to-configuration machine: (finished: must succeed: ! test -e /run/current-system/bin/switch-to-configuration, in 0.01 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/unitWithMultilineValue/bin/switch-to-configuration test 2>&1 machine # [ 34.337276] nixos[2740]: switching to system configuration /nix/store/bwj6gpy653sagl5wlvs6hvhxbi1k23xp-nixos-system-machine-test machine # [ 34.339927] systemd[1]: test.service: Deactivated successfully. machine # [ 34.341237] systemd[1]: Stopped test.service. machine # [ 34.342545] systemd[1]: Stopped target Remote File Systems. machine # [ 34.343940] systemd[1]: Stopped target Local File Systems. machine # [ 34.564741] systemd[1]: Reload requested from client PID 2740 ('.switch-to-conf') (unit backdoor.service)... machine # [ 34.566352] systemd[1]: Reloading... machine # [ 34.808702] systemd[1]: Reloading finished in 240 ms. machine # [ 34.866753] systemd[628]: Reexecution requested from client PID 2809 ('switch-to-confi')... machine # [ 34.869266] systemd[628]: Reexecuting. machine # [ 34.889219] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.965705] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.971183] systemd[628]: Reload requested from client PID 2809 ('switch-to-confi')... machine # [ 34.972577] systemd[628]: Reloading... machine # [ 35.046747] systemd[628]: Reloading finished in 72 ms. machine # [ 35.051837] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.058248] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 35.078602] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 35.082094] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 35.082960] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 35.085936] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 35.087339] systemd[1]: Stopping Reactivate sysinit units... machine # [ 35.088595] systemd[1]: Reached target Reactivate sysinit units. machine # [ 35.090097] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 35.093256] systemd[1]: Reached target Remote File Systems. machine # [ 35.098186] systemd[1]: Reached target Local File Systems. machine # [ 35.098991] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 35.102927] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 35.106713] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 35.108124] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 35.110754] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 35.114198] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 35.115388] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 35.125992] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 35.128958] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 35.142146] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 35.261984] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 35.263591] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 35.268076] systemd[1]: Starting linger-users.service... machine # [ 35.270122] systemd[1]: Started test.service. machine # [ 35.293390] systemd[1]: test.service: Deactivated successfully. machine # [ 35.307640] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 35.309339] systemd[1]: Finished linger-users.service. machine # [ 35.566689] nixos[2740]: finished switching to system configuration /nix/store/bwj6gpy653sagl5wlvs6hvhxbi1k23xp-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/unitWithMultilineValue/bin/switch-to-configuration test 2>&1, in 1.44 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/unitWithBackslash/bin/switch-to-configuration test 2>&1 machine # [ 35.798629] nixos[2885]: switching to system configuration /nix/store/hapwxzsmb0a8gf1y08ifvxvmqgb9nlk9-nixos-system-machine-test machine # [ 35.801158] systemd[1]: Stopped target Remote File Systems. machine # [ 35.802705] systemd[1]: Stopped target Local File Systems. machine # [ 36.028358] systemd[1]: Reload requested from client PID 2885 ('.switch-to-conf') (unit backdoor.service)... machine # [ 36.029949] systemd[1]: Reloading... machine # [ 36.273091] systemd[1]: Reloading finished in 241 ms. machine # [ 36.333548] systemd[628]: Reexecution requested from client PID 2954 ('switch-to-confi')... machine # [ 36.336055] systemd[628]: Reexecuting. machine # [ 36.357027] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.426891] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.432187] systemd[628]: Reload requested from client PID 2954 ('switch-to-confi')... machine # [ 36.433902] systemd[628]: Reloading... machine # [ 36.504483] systemd[628]: Reloading finished in 69 ms. machine # [ 36.510821] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.516977] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 36.517934] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 36.520455] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 36.539351] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 36.542338] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 36.543637] systemd[1]: Stopping Reactivate sysinit units... machine # [ 36.544727] systemd[1]: Reached target Reactivate sysinit units. machine # [ 36.548872] systemd[1]: Reached target Remote File Systems. machine # [ 36.555134] systemd[1]: Reached target Local File Systems. machine # [ 36.555952] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 36.559206] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 36.560876] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 36.562138] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 36.564119] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 36.565582] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.566767] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.572280] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.573713] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 36.700561] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 36.703251] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 36.705963] systemd[1]: Starting escaped\x2ddash.service... machine # [ 36.708688] systemd[1]: Starting linger-users.service... machine # [ 36.730887] systemd[1]: Finished escaped\x2ddash.service. machine # [ 36.743821] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 36.745277] systemd[1]: Finished linger-users.service. machine # [ 37.001926] nixos[2885]: finished switching to system configuration /nix/store/hapwxzsmb0a8gf1y08ifvxvmqgb9nlk9-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/unitWithBackslash/bin/switch-to-configuration test 2>&1, in 1.44 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/unitWithBackslashModified/bin/switch-to-configuration test 2>&1 machine # [ 37.235671] nixos[3027]: switching to system configuration /nix/store/5b63yigjyjs9zrh6157q5qwns1wzm7fy-nixos-system-machine-test machine # [ 37.238605] systemd[1]: escaped\x2ddash.service: Deactivated successfully. machine # [ 37.239543] systemd[1]: Stopped escaped\x2ddash.service. machine # [ 37.241936] systemd[1]: Stopped target Remote File Systems. machine # [ 37.242843] systemd[1]: Stopped target Local File Systems. machine # [ 37.446644] systemd[1]: Reload requested from client PID 3027 ('.switch-to-conf') (unit backdoor.service)... machine # [ 37.448510] systemd[1]: Reloading... machine # [ 37.697027] systemd[1]: Reloading finished in 247 ms. machine # [ 37.760242] systemd[628]: Reexecution requested from client PID 3097 ('switch-to-confi')... machine # [ 37.762688] systemd[628]: Reexecuting. machine # [ 37.783054] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.854105] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.859515] systemd[628]: Reload requested from client PID 3097 ('switch-to-confi')... machine # [ 37.860903] systemd[628]: Reloading... machine # [ 37.932415] systemd[628]: Reloading finished in 70 ms. machine # [ 37.937826] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.943975] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 37.945805] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 37.947500] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 37.966133] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 37.969062] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 37.970557] systemd[1]: Stopping Reactivate sysinit units... machine # [ 37.971669] systemd[1]: Reached target Reactivate sysinit units. machine # [ 37.976469] systemd[1]: Reached target Remote File Systems. machine # [ 37.979917] systemd[1]: Starting escaped\x2ddash.service... machine # [ 37.981712] systemd[1]: Starting linger-users.service... machine # [ 37.992100] systemd[1]: Reached target Local File Systems. machine # [ 37.992854] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 37.995458] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 37.997662] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 38.000921] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 38.004635] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 38.007236] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 38.010868] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 38.012632] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 38.015123] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 38.058591] systemd[1]: Finished escaped\x2ddash.service. machine # [ 38.080779] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 38.081950] systemd[1]: Finished linger-users.service. machine # [ 38.188096] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 38.190575] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 38.444843] nixos[3027]: finished switching to system configuration /nix/store/5b63yigjyjs9zrh6157q5qwns1wzm7fy-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/unitWithBackslashModified/bin/switch-to-configuration test 2>&1, in 1.44 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/unitStartingWithDash/bin/switch-to-configuration test 2>&1 machine # [ 38.674568] nixos[3170]: switching to system configuration /nix/store/n83as3n8m14gk6xa193r0xc3j1y2y5iq-nixos-system-machine-test machine # [ 38.676979] systemd[1]: Stopped target Remote File Systems. machine # [ 38.679237] systemd[1]: escaped\x2ddash.service: Deactivated successfully. machine # [ 38.680724] systemd[1]: Stopped escaped\x2ddash.service. machine # [ 38.681865] systemd[1]: Stopped target Local File Systems. machine # [ 38.906237] systemd[1]: Reload requested from client PID 3170 ('.switch-to-conf') (unit backdoor.service)... machine # [ 38.907762] systemd[1]: Reloading... machine # [ 39.161824] systemd[1]: Reloading finished in 252 ms. machine # [ 39.220720] systemd[628]: Reexecution requested from client PID 3239 ('switch-to-confi')... machine # [ 39.222667] systemd[628]: Reexecuting. machine # [ 39.244029] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.313643] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.319623] systemd[628]: Reload requested from client PID 3239 ('switch-to-confi')... machine # [ 39.321852] systemd[628]: Reloading... machine # [ 39.392270] systemd[628]: Reloading finished in 70 ms. machine # [ 39.397828] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.404928] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 39.405771] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 39.407544] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 39.427706] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 39.430780] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 39.432355] systemd[1]: Stopping Reactivate sysinit units... machine # [ 39.433477] systemd[1]: Reached target Reactivate sysinit units. machine # [ 39.435076] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.436661] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 39.438865] systemd[1]: Reached target Local File Systems. machine # [ 39.443941] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 39.448418] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 39.449279] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 39.450459] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 39.452498] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 39.454022] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.455236] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.461174] systemd[1]: Reached target Remote File Systems. machine # [ 39.591280] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 39.592958] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 39.596864] systemd[1]: Starting -.service... machine # [ 39.600640] systemd[1]: Starting linger-users.service... machine # [ 39.623412] systemd[1]: Finished -.service. machine # [ 39.636916] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 39.639128] systemd[1]: Finished linger-users.service. machine # [ 39.895984] nixos[3170]: finished switching to system configuration /nix/store/n83as3n8m14gk6xa193r0xc3j1y2y5iq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/unitStartingWithDash/bin/switch-to-configuration test 2>&1, in 1.45 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/unitStartingWithDashModified/bin/switch-to-configuration test 2>&1 machine # [ 40.126477] nixos[3312]: switching to system configuration /nix/store/wkrji2zz5yqxg17kfhy8jghqvdy2fxda-nixos-system-machine-test machine # [ 40.130651] systemd[1]: Stopped target Local File Systems. machine # [ 40.131637] systemd[1]: Stopped target Remote File Systems. machine # [ 40.400223] systemd[1]: Reload requested from client PID 3312 ('.switch-to-conf') (unit backdoor.service)... machine # [ 40.401880] systemd[1]: Reloading... machine # [ 40.642677] systemd[1]: Reloading finished in 239 ms. machine # [ 40.704307] systemd[628]: Reexecution requested from client PID 3382 ('switch-to-confi')... machine # [ 40.705579] systemd[628]: Reexecuting. machine # [ 40.726038] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 40.797918] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 40.803940] systemd[628]: Reload requested from client PID 3382 ('switch-to-confi')... machine # [ 40.805994] systemd[628]: Reloading... machine # [ 40.877351] systemd[628]: Reloading finished in 71 ms. machine # [ 40.882849] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 40.889286] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 40.890205] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 40.892265] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 40.911266] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 40.914300] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 40.915610] systemd[1]: Stopping Reactivate sysinit units... machine # [ 40.916829] systemd[1]: Reached target Reactivate sysinit units. machine # [ 40.919731] systemd[1]: Reloading -.service... machine # [ 40.934607] systemd[1]: Reloaded -.service. machine # [ 40.938970] systemd[1]: Reached target Remote File Systems. machine # [ 40.940343] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 40.942858] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 40.944615] systemd[1]: Starting linger-users.service... machine # [ 40.951165] systemd[1]: Reached target Local File Systems. machine # [ 40.951898] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 40.955567] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 40.956418] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 40.958256] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 40.960215] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 40.961646] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 40.966101] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 41.006481] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 41.008569] systemd[1]: Finished linger-users.service. machine # [ 41.117209] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 41.118844] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 41.373868] nixos[3312]: finished switching to system configuration /nix/store/wkrji2zz5yqxg17kfhy8jghqvdy2fxda-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/unitStartingWithDashModified/bin/switch-to-configuration test 2>&1, in 1.47 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/unitWithRequirement/bin/switch-to-configuration test 2>&1 machine # [ 41.582712] nixos[3455]: switching to system configuration /nix/store/7c1s6zp1z3nxqxiqy791myi5ay869jdb-nixos-system-machine-test machine # [ 41.585406] systemd[1]: Stopped target Local File Systems. machine # [ 41.587324] systemd[1]: Stopped target Remote File Systems. machine # [ 41.588463] systemd[1]: -.service: Deactivated successfully. machine # [ 41.589927] systemd[1]: Stopped -.service. machine # [ 41.825965] systemd[1]: Reload requested from client PID 3455 ('.switch-to-conf') (unit backdoor.service)... machine # [ 41.827681] systemd[1]: Reloading... machine # [ 42.083708] systemd[1]: Reloading finished in 254 ms. machine # [ 42.145874] systemd[628]: Reexecution requested from client PID 3524 ('switch-to-confi')... machine # [ 42.147896] systemd[628]: Reexecuting. machine # [ 42.169065] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.242772] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.247985] systemd[628]: Reload requested from client PID 3524 ('switch-to-confi')... machine # [ 42.249371] systemd[628]: Reloading... machine # [ 42.320994] systemd[628]: Reloading finished in 70 ms. machine # [ 42.325856] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.331953] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 42.333336] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 42.335540] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 42.354502] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 42.357501] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 42.358769] systemd[1]: Stopping Reactivate sysinit units... machine # [ 42.359824] systemd[1]: Reached target Reactivate sysinit units. machine # [ 42.362894] systemd[1]: Reached target Remote File Systems. machine # [ 42.365056] systemd[1]: Reached target Local File Systems. machine # [ 42.367179] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 42.370282] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 42.371754] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 42.372972] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 42.374891] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 42.376328] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 42.377490] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 42.382389] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 42.383607] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 42.517460] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 42.521416] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 42.524406] systemd[1]: Starting linger-users.service... machine # [ 42.526172] systemd[1]: Starting required-service.service... machine # [ 42.531160] systemd[1]: Starting test-service.service... machine # [ 42.559724] systemd[1]: Finished test-service.service. machine # [ 42.563030] systemd[1]: Finished required-service.service. machine # [ 42.576483] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 42.578071] systemd[1]: Finished linger-users.service. machine # [ 42.832576] nixos[3455]: finished switching to system configuration /nix/store/7c1s6zp1z3nxqxiqy791myi5ay869jdb-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/unitWithRequirement/bin/switch-to-configuration test 2>&1, in 1.46 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/unitWithRequirementModified/bin/switch-to-configuration test 2>&1 machine # [ 43.044954] nixos[3599]: switching to system configuration /nix/store/bmadjnvqbdcrbmax3pwja557ryh7wc5n-nixos-system-machine-test machine # [ 43.047551] systemd[1]: Stopped target Local File Systems. machine # [ 43.049066] systemd[1]: Stopped target Remote File Systems. machine # [ 43.051060] systemd[1]: required-service.service: Deactivated successfully. machine # [ 43.051978] systemd[1]: Stopped required-service.service. machine # [ 43.053536] systemd[1]: test-service.service: Deactivated successfully. machine # [ 43.054794] systemd[1]: Stopped test-service.service. machine # [ 43.292639] systemd[1]: Reload requested from client PID 3599 ('.switch-to-conf') (unit backdoor.service)... machine # [ 43.294396] systemd[1]: Reloading... machine # [ 43.545894] systemd[1]: Reloading finished in 250 ms. machine # [ 43.617215] systemd[628]: Reexecution requested from client PID 3668 ('switch-to-confi')... machine # [ 43.620212] systemd[628]: Reexecuting. machine # [ 43.643216] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.721726] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.727851] systemd[628]: Reload requested from client PID 3668 ('switch-to-confi')... machine # [ 43.729100] systemd[628]: Reloading... machine # [ 43.800978] systemd[628]: Reloading finished in 70 ms. machine # [ 43.806851] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.814063] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 43.815041] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 43.816813] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 43.837611] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 43.840823] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 43.842280] systemd[1]: Stopping Reactivate sysinit units... machine # [ 43.843308] systemd[1]: Reached target Reactivate sysinit units. machine # [ 43.849326] systemd[1]: Starting required-service.service... machine # [ 43.854545] systemd[1]: Reached target Local File Systems. machine # [ 43.855449] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 43.858350] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 43.860311] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 43.861518] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 43.863427] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 43.867464] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.868662] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.871257] systemd[1]: Reached target Remote File Systems. machine # [ 43.876652] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.878087] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 43.896153] systemd[1]: Finished required-service.service. machine # [ 44.008607] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 44.010286] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 44.013885] systemd[1]: Starting linger-users.service... machine # [ 44.016601] systemd[1]: Starting test-service.service... machine # [ 44.038226] systemd[1]: Finished test-service.service. machine # [ 44.051925] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 44.053385] systemd[1]: Finished linger-users.service. machine # [ 44.311417] nixos[3599]: finished switching to system configuration /nix/store/bmadjnvqbdcrbmax3pwja557ryh7wc5n-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/unitWithRequirementModified/bin/switch-to-configuration test 2>&1, in 1.48 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/unitWithRequirementModifiedNostart/bin/switch-to-configuration test 2>&1 machine # [ 44.548919] nixos[3743]: switching to system configuration /nix/store/9sms0gscrsmm5aml2wqf8wa9m0cnvra5-nixos-system-machine-test machine # [ 44.551247] systemd[1]: Stopped target Remote File Systems. machine # [ 44.552769] systemd[1]: Stopped target Local File Systems. machine # [ 44.554536] systemd[1]: required-service.service: Deactivated successfully. machine # [ 44.556291] systemd[1]: Stopped required-service.service. machine # [ 44.557102] systemd[1]: test-service.service: Deactivated successfully. machine # [ 44.557930] systemd[1]: Stopped test-service.service. machine # [ 44.784381] systemd[1]: Reload requested from client PID 3743 ('.switch-to-conf') (unit backdoor.service)... machine # [ 44.786194] systemd[1]: Reloading... machine # [ 45.035155] systemd[1]: Reloading finished in 247 ms. machine # [ 45.099195] systemd[628]: Reexecution requested from client PID 3812 ('switch-to-confi')... machine # [ 45.101113] systemd[628]: Reexecuting. machine # [ 45.122500] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.193935] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.199746] systemd[628]: Reload requested from client PID 3812 ('switch-to-confi')... machine # [ 45.201178] systemd[628]: Reloading... machine # [ 45.273816] systemd[628]: Reloading finished in 71 ms. machine # [ 45.278832] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.285060] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 45.285961] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 45.288486] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 45.307248] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 45.310511] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 45.312533] systemd[1]: Stopping Reactivate sysinit units... machine # [ 45.313464] systemd[1]: Reached target Reactivate sysinit units. machine # [ 45.316419] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 45.317903] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 45.320335] systemd[1]: Reached target Local File Systems. machine # [ 45.322255] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 45.324661] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 45.326502] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 45.328353] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 45.330302] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 45.331700] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 45.332847] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 45.339424] systemd[1]: Reached target Remote File Systems. machine # [ 45.466495] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 45.468282] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 45.472857] systemd[1]: Starting linger-users.service... machine # [ 45.475112] systemd[1]: Starting required-service.service... machine # [ 45.480348] systemd[1]: Starting test-service.service... machine # [ 45.509261] systemd[1]: Finished required-service.service. machine # [ 45.510432] systemd[1]: Finished test-service.service. machine # [ 45.523507] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 45.524980] systemd[1]: Finished linger-users.service. machine # [ 45.782424] nixos[3743]: finished switching to system configuration /nix/store/9sms0gscrsmm5aml2wqf8wa9m0cnvra5-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/unitWithRequirementModifiedNostart/bin/switch-to-configuration test 2>&1, in 1.47 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/unitWithTemplate/bin/switch-to-configuration test 2>&1 machine # [ 46.013592] nixos[3887]: switching to system configuration /nix/store/3ym6pnvfdngaywkvdsp3svkpdv4hlpwx-nixos-system-machine-test machine # [ 46.015909] systemd[1]: required-service.service: Deactivated successfully. machine # [ 46.017724] systemd[1]: Stopped required-service.service. machine # [ 46.018793] systemd[1]: test-service.service: Deactivated successfully. machine # [ 46.020366] systemd[1]: Stopped test-service.service. machine # [ 46.021587] systemd[1]: Stopped target Local File Systems. machine # [ 46.023145] systemd[1]: Stopped target Remote File Systems. machine # [ 46.246091] systemd[1]: Reload requested from client PID 3887 ('.switch-to-conf') (unit backdoor.service)... machine # [ 46.247655] systemd[1]: Reloading... machine # [ 46.487966] systemd[1]: Reloading finished in 238 ms. machine # [ 46.550188] systemd[628]: Reexecution requested from client PID 3956 ('switch-to-confi')... machine # [ 46.552130] systemd[628]: Reexecuting. machine # [ 46.573280] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.643320] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.648126] systemd[628]: Reload requested from client PID 3956 ('switch-to-confi')... machine # [ 46.649796] systemd[628]: Reloading... machine # [ 46.719561] systemd[628]: Reloading finished in 67 ms. machine # [ 46.724843] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.732065] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 46.732870] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 46.734706] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 46.755194] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 46.758198] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 46.759491] systemd[1]: Stopping Reactivate sysinit units... machine # [ 46.760425] systemd[1]: Reached target Reactivate sysinit units. machine # [ 46.764722] systemd[1]: Created slice Slice /system/instantiated. machine # [ 46.766213] systemd[1]: Reached target Remote File Systems. machine # [ 46.768937] systemd[1]: Starting instantiated@one.service... machine # [ 46.771495] systemd[1]: Starting instantiated@two.service... machine # [ 46.774172] systemd[1]: Starting linger-users.service... machine # [ 46.778066] systemd[1]: Reached target Local File Systems. machine # [ 46.783057] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 46.788456] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 46.789303] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 46.790445] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 46.792376] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 46.793863] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.795060] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.803421] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.805579] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 46.833349] systemd[1]: Finished instantiated@one.service. machine # [ 46.835477] systemd[1]: Finished instantiated@two.service. machine # [ 46.860715] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 46.862705] systemd[1]: Finished linger-users.service. machine # [ 46.965939] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 46.967651] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 47.225355] nixos[3887]: finished switching to system configuration /nix/store/3ym6pnvfdngaywkvdsp3svkpdv4hlpwx-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/unitWithTemplate/bin/switch-to-configuration test 2>&1, in 1.44 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/unitWithTemplateModified/bin/switch-to-configuration test 2>&1 machine # [ 47.463811] nixos[4031]: switching to system configuration /nix/store/v2q9rzybxn9qmfxp244q4d53kq3jciwv-nixos-system-machine-test machine # [ 47.466073] systemd[1]: instantiated@two.service: Deactivated successfully. machine # [ 47.467693] systemd[1]: Stopped instantiated@two.service. machine # [ 47.468972] systemd[1]: Stopped target Remote File Systems. machine # [ 47.470341] systemd[1]: instantiated@one.service: Deactivated successfully. machine # [ 47.471975] systemd[1]: Stopped instantiated@one.service. machine # [ 47.473206] systemd[1]: Stopped target Local File Systems. machine # [ 47.700316] systemd[1]: Reload requested from client PID 4031 ('.switch-to-conf') (unit backdoor.service)... machine # [ 47.703040] systemd[1]: Reloading... machine # [ 47.942895] systemd[1]: Reloading finished in 240 ms. machine # [ 48.002386] systemd[628]: Reexecution requested from client PID 4100 ('switch-to-confi')... machine # [ 48.004914] systemd[628]: Reexecuting. machine # [ 48.024995] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.096532] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.101617] systemd[628]: Reload requested from client PID 4100 ('switch-to-confi')... machine # [ 48.103229] systemd[628]: Reloading... machine # [ 48.174278] systemd[628]: Reloading finished in 70 ms. machine # [ 48.179827] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.186370] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 48.187268] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 48.189416] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 48.209186] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 48.212264] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 48.213569] systemd[1]: Stopping Reactivate sysinit units... machine # [ 48.214534] systemd[1]: Reached target Reactivate sysinit units. machine # [ 48.218071] systemd[1]: Reached target Remote File Systems. machine # [ 48.222112] systemd[1]: Starting instantiated@one.service... machine # [ 48.225135] systemd[1]: Starting instantiated@two.service... machine # [ 48.228053] systemd[1]: Starting linger-users.service... machine # [ 48.232187] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.233374] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 48.243079] systemd[1]: Reached target Local File Systems. machine # [ 48.243836] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 48.248144] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 48.248918] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 48.253350] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 48.257386] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 48.259812] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.262464] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.288290] systemd[1]: Finished instantiated@one.service. machine # [ 48.291282] systemd[1]: Finished instantiated@two.service. machine # [ 48.312089] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 48.314438] systemd[1]: Finished linger-users.service. machine # [ 48.423601] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 48.425424] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 48.683278] nixos[4031]: finished switching to system configuration /nix/store/v2q9rzybxn9qmfxp244q4d53kq3jciwv-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/unitWithTemplateModified/bin/switch-to-configuration test 2>&1, in 1.46 seconds) (finished: subtest: services, in 30.42 seconds) subtest: failing units machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1 machine # [ 48.905070] nixos[4175]: switching to system configuration /nix/store/gpjbpszcj0argzwqlwxdij9bimgvkxv8-nixos-system-machine-test machine # [ 48.907457] systemd[1]: instantiated@two.service: Deactivated successfully. machine # [ 48.909639] systemd[1]: Stopped instantiated@two.service. machine # [ 48.910812] systemd[1]: instantiated@one.service: Deactivated successfully. machine # [ 48.911815] systemd[1]: Stopped instantiated@one.service. machine # [ 48.913321] systemd[1]: Stopped target Local File Systems. machine # [ 48.914489] systemd[1]: Stopped target Remote File Systems. machine # [ 49.119446] systemd[1]: Reload requested from client PID 4175 ('.switch-to-conf') (unit backdoor.service)... machine # [ 49.121108] systemd[1]: Reloading... machine # [ 49.358569] systemd[1]: Reloading finished in 236 ms. machine # [ 49.421803] systemd[628]: Reexecution requested from client PID 4244 ('switch-to-confi')... machine # [ 49.424700] systemd[628]: Reexecuting. machine # [ 49.446341] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.519366] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.524609] systemd[628]: Reload requested from client PID 4244 ('switch-to-confi')... machine # [ 49.526658] systemd[628]: Reloading... machine # [ 49.599462] systemd[628]: Reloading finished in 73 ms. machine # [ 49.604869] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.612074] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 49.612920] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 49.615063] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 49.635675] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 49.638802] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 49.640384] systemd[1]: Stopping Reactivate sysinit units... machine # [ 49.641440] systemd[1]: Reached target Reactivate sysinit units. machine # [ 49.643272] systemd[1]: Reached target Remote File Systems. machine # [ 49.647069] systemd[1]: Reached target Local File Systems. machine # [ 49.647843] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 49.651280] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 49.652936] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 49.654144] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 49.656226] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 49.657731] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 49.658975] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 49.663629] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 49.664894] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 49.795764] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 49.797456] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 49.802134] systemd[1]: Starting linger-users.service... machine # [ 49.804183] systemd[1]: Starting test.service... machine # [ 49.827616] systemd[1]: Finished test.service. machine # [ 49.841355] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 49.843939] systemd[1]: Finished linger-users.service. machine # [ 50.100961] nixos[4175]: finished switching to system configuration /nix/store/gpjbpszcj0argzwqlwxdij9bimgvkxv8-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1, in 1.42 seconds) machine: must fail: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceFailing/bin/switch-to-configuration test 2>&1 machine # [ 50.339850] nixos[4317]: switching to system configuration /nix/store/48cwanqhlhzsnsha153s5p5c8g122vlz-nixos-system-machine-test machine # [ 50.342395] systemd[1]: Stopped target Remote File Systems. machine # [ 50.343951] systemd[1]: Stopped target Local File Systems. machine # [ 50.345661] systemd[1]: test.service: Deactivated successfully. machine # [ 50.346760] systemd[1]: Stopped test.service. machine # [ 50.571205] systemd[1]: Reload requested from client PID 4317 ('.switch-to-conf') (unit backdoor.service)... machine # [ 50.572824] systemd[1]: Reloading... machine # [ 50.815709] systemd[1]: Reloading finished in 241 ms. machine # [ 50.879349] systemd[628]: Reexecution requested from client PID 4386 ('switch-to-confi')... machine # [ 50.881889] systemd[628]: Reexecuting. machine # [ 50.903267] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 50.974162] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 50.979361] systemd[628]: Reload requested from client PID 4386 ('switch-to-confi')... machine # [ 50.980568] systemd[628]: Reloading... machine # [ 51.052068] systemd[628]: Reloading finished in 69 ms. machine # [ 51.057845] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.064252] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 51.066059] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 51.067827] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 51.089534] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 51.092854] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 51.094381] systemd[1]: Stopping Reactivate sysinit units... machine # [ 51.095549] systemd[1]: Reached target Reactivate sysinit units. machine # [ 51.096877] systemd[1]: Reached target Local File Systems. machine # [ 51.102493] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.104309] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 51.108470] systemd[1]: Reached target Remote File Systems. machine # [ 51.112931] systemd[1]: Starting test.service... machine # [ 51.119452] systemd[1]: Starting linger-users.service... machine # [ 51.123813] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 51.127154] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 51.127897] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 51.128996] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 51.131084] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 51.132489] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.133682] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.155902] systemd[1]: test.service: Main process exited, code=exited, status=1/FAILURE machine # [ 51.158461] systemd[1]: test.service: Failed with result 'exit-code'. machine # [ 51.159815] systemd[1]: Failed to start test.service. machine # [ 51.187985] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 51.190832] systemd[1]: Finished linger-users.service. machine # [ 51.298486] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 51.300286] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 51.578841] nixos[4317]: switching to system configuration /nix/store/48cwanqhlhzsnsha153s5p5c8g122vlz-nixos-system-machine-test failed (status 4) machine: (finished: must fail: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceFailing/bin/switch-to-configuration test 2>&1, in 1.48 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1 machine # [ 51.802837] nixos[4460]: switching to system configuration /nix/store/y7lvzfwmpdli3rpd5wxkjy0xvpmx1g88-nixos-system-machine-test machine # [ 51.805148] systemd[1]: Stopped target Local File Systems. machine # [ 51.806745] systemd[1]: Stopped target Remote File Systems. machine # [ 52.031492] systemd[1]: Reload requested from client PID 4460 ('.switch-to-conf') (unit backdoor.service)... machine # [ 52.033143] systemd[1]: Reloading... machine # [ 52.278955] systemd[1]: Reloading finished in 244 ms. machine # [ 52.345407] systemd[628]: Reexecution requested from client PID 4529 ('switch-to-confi')... machine # [ 52.346694] systemd[628]: Reexecuting. machine # [ 52.367976] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.439589] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.445418] systemd[628]: Reload requested from client PID 4529 ('switch-to-confi')... machine # [ 52.446829] systemd[628]: Reloading... machine # [ 52.525391] systemd[628]: Reloading finished in 77 ms. machine # [ 52.530831] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.536813] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 52.537826] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 52.540184] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 52.559293] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 52.562286] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 52.563627] systemd[1]: Stopping Reactivate sysinit units... machine # [ 52.564782] systemd[1]: Reached target Reactivate sysinit units. machine # [ 52.571574] systemd[1]: Reached target Remote File Systems. machine # [ 52.575063] systemd[1]: Started autorestart.service. machine # [ 52.577423] systemd[1]: Starting linger-users.service... machine # [ 52.584517] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 52.585751] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 52.587034] systemd[1]: Reached target Local File Systems. machine # [ 52.592534] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 52.596209] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 52.597055] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 52.598216] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 52.600238] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 52.603150] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 52.605147] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 52.622966] systemd[1]: autorestart.service: Deactivated successfully. machine # [ 52.646875] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 52.649142] systemd[1]: Finished linger-users.service. machine # [ 52.756115] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 52.757927] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 53.016310] nixos[4460]: finished switching to system configuration /nix/store/y7lvzfwmpdli3rpd5wxkjy0xvpmx1g88-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1, in 1.44 seconds) machine # [ 53.065291] systemd[1]: Stopped autorestart.service. machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1 machine # [ 53.249354] nixos[4607]: switching to system configuration /nix/store/y7lvzfwmpdli3rpd5wxkjy0xvpmx1g88-nixos-system-machine-test machine # [ 53.251545] systemd[1]: Stopped target Remote File Systems. machine # [ 53.253172] systemd[1]: Stopped target Local File Systems. machine # [ 53.463516] systemd[1]: Reload requested from client PID 4607 ('.switch-to-conf') (unit backdoor.service)... machine # [ 53.465160] systemd[1]: Reloading... machine # [ 53.702854] systemd[1]: Reloading finished in 236 ms. machine # [ 53.765951] systemd[628]: Reexecution requested from client PID 4676 ('switch-to-confi')... machine # [ 53.767928] systemd[628]: Reexecuting. machine # [ 53.789107] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.865727] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.870812] systemd[628]: Reload requested from client PID 4676 ('switch-to-confi')... machine # [ 53.872052] systemd[628]: Reloading... machine # [ 53.946718] systemd[628]: Reloading finished in 73 ms. machine # [ 53.951840] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.957785] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 53.959322] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 53.961296] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 53.980617] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 53.983855] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 53.984915] systemd[1]: Stopping Reactivate sysinit units... machine # [ 53.985906] systemd[1]: Reached target Reactivate sysinit units. machine # [ 53.988648] systemd[1]: Reached target Remote File Systems. machine # [ 53.991284] systemd[1]: Started autorestart.service. machine # [ 53.994061] systemd[1]: Starting linger-users.service... machine # [ 53.997126] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.998346] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 54.013910] systemd[1]: Reached target Local File Systems. machine # [ 54.016216] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 54.019491] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 54.020964] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 54.023318] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 54.025668] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 54.028103] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 54.029248] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 54.044465] systemd[1]: autorestart.service: Deactivated successfully. machine # [ 54.068891] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 54.070751] systemd[1]: Finished linger-users.service. machine # [ 54.182848] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 54.184494] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 54.443456] nixos[4607]: finished switching to system configuration /nix/store/y7lvzfwmpdli3rpd5wxkjy0xvpmx1g88-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1, in 1.38 seconds) machine # [ 54.490525] systemd[1]: Stopped autorestart.service. machine: must fail: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/autorestartServiceFailing/bin/switch-to-configuration test 2>&1 machine # [ 54.703483] nixos[4754]: switching to system configuration /nix/store/bjag53lyi3bh60l7ij85yz3h3l1d16m4-nixos-system-machine-test machine # [ 54.705605] systemd[1]: Stopped target Remote File Systems. machine # [ 54.707331] systemd[1]: Stopped target Local File Systems. machine # [ 54.927616] systemd[1]: Reload requested from client PID 4754 ('.switch-to-conf') (unit backdoor.service)... machine # [ 54.929154] systemd[1]: Reloading... machine # [ 55.167928] systemd[1]: Reloading finished in 237 ms. machine # [ 55.230393] systemd[628]: Reexecution requested from client PID 4823 ('switch-to-confi')... machine # [ 55.231675] systemd[628]: Reexecuting. machine # [ 55.251099] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.322355] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.327431] systemd[628]: Reload requested from client PID 4823 ('switch-to-confi')... machine # [ 55.328731] systemd[628]: Reloading... machine # [ 55.399724] systemd[628]: Reloading finished in 69 ms. machine # [ 55.403842] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.409833] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 55.411381] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 55.413436] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 55.433058] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 55.436373] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 55.437419] systemd[1]: Stopping Reactivate sysinit units... machine # [ 55.438428] systemd[1]: Reached target Reactivate sysinit units. machine # [ 55.444376] systemd[1]: Reached target Local File Systems. machine # [ 55.446222] systemd[1]: Reached target Remote File Systems. machine # [ 55.448172] systemd[1]: Started autorestart.service. machine # [ 55.451493] systemd[1]: Starting linger-users.service... machine # [ 55.459546] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 55.464935] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 55.466255] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 55.468406] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 55.471313] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 55.474091] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.476853] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.481174] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.484112] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 55.498556] systemd[1]: autorestart.service: Main process exited, code=exited, status=1/FAILURE machine # [ 55.500513] systemd[1]: autorestart.service: Failed with result 'exit-code'. machine # [ 55.524790] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 55.526356] systemd[1]: Finished linger-users.service. machine # [ 55.632605] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 55.634928] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 55.915931] nixos[4754]: switching to system configuration /nix/store/bjag53lyi3bh60l7ij85yz3h3l1d16m4-nixos-system-machine-test failed (status 4) machine: (finished: must fail: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/autorestartServiceFailing/bin/switch-to-configuration test 2>&1, in 1.43 seconds) (finished: subtest: failing units, in 7.23 seconds) subtest: unit file parser machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 56.115675] nixos[4897]: switching to system configuration /nix/store/hrg0892sd5m6szy0q1g0vjwv0ashwlyw-nixos-system-machine-test machine # [ 56.118061] systemd[1]: Stopped autorestart.service. machine # [ 56.119873] systemd[1]: Stopped target Remote File Systems. machine # [ 56.121274] systemd[1]: Stopped target Local File Systems. machine # [ 56.322241] systemd[1]: Reload requested from client PID 4897 ('.switch-to-conf') (unit backdoor.service)... machine # [ 56.323931] systemd[1]: Reloading... machine # [ 56.551123] systemd[1]: Reloading finished in 226 ms. machine # [ 56.612848] systemd[628]: Reexecution requested from client PID 4966 ('switch-to-confi')... machine # [ 56.615146] systemd[628]: Reexecuting. machine # [ 56.636216] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 56.712107] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 56.717439] systemd[628]: Reload requested from client PID 4966 ('switch-to-confi')... machine # [ 56.718858] systemd[628]: Reloading... machine # [ 56.790880] systemd[628]: Reloading finished in 70 ms. machine # [ 56.795840] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 56.801970] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 56.803915] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 56.805461] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 56.827063] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 56.829934] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 56.831260] systemd[1]: Stopping Reactivate sysinit units... machine # [ 56.832299] systemd[1]: Reached target Reactivate sysinit units. machine # [ 56.836536] systemd[1]: Reached target Remote File Systems. machine # [ 56.839948] systemd[1]: Starting linger-users.service... machine # [ 56.841814] systemd[1]: Starting test.service... machine # [ 56.848448] systemd[1]: Reached target Local File Systems. machine # [ 56.849321] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 56.852368] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 56.853238] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 56.854499] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 56.856454] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 56.860122] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 56.863098] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 56.866155] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 56.867375] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 56.895494] systemd[1]: Finished test.service. machine # [ 56.917378] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 56.919205] systemd[1]: Finished linger-users.service. machine # [ 57.025982] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 57.027674] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 57.285920] nixos[4897]: finished switching to system configuration /nix/store/hrg0892sd5m6szy0q1g0vjwv0ashwlyw-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 1.37 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceWithExtraSection/bin/switch-to-configuration test 2>&1 machine # [ 57.533511] nixos[5039]: switching to system configuration /nix/store/7znq3n41vzi5ir6d7cz9wf3bm5bins1v-nixos-system-machine-test machine # [ 57.535881] systemd[1]: Stopped target Remote File Systems. machine # [ 57.537474] systemd[1]: Stopped target Local File Systems. machine # [ 57.762581] systemd[1]: Reload requested from client PID 5039 ('.switch-to-conf') (unit backdoor.service)... machine # [ 57.764361] systemd[1]: Reloading... machine # [ 58.003820] systemd[1]: Reloading finished in 237 ms. machine # [ 58.067425] systemd[628]: Reexecution requested from client PID 5108 ('switch-to-confi')... machine # [ 58.070027] systemd[628]: Reexecuting. machine # [ 58.091121] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 58.164389] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 58.169449] systemd[628]: Reload requested from client PID 5108 ('switch-to-confi')... machine # [ 58.171326] systemd[628]: Reloading... machine # [ 58.242340] systemd[628]: Reloading finished in 70 ms. machine # [ 58.247842] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 58.254876] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 58.255745] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 58.257459] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 58.276361] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 58.279404] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 58.280617] systemd[1]: Stopping Reactivate sysinit units... machine # [ 58.281689] systemd[1]: Reached target Reactivate sysinit units. machine # [ 58.283339] systemd[1]: test.service: Deactivated successfully. machine # [ 58.285202] systemd[1]: Stopped test.service. machine # [ 58.285804] systemd[1]: Stopping test.service... machine # [ 58.287593] systemd[1]: Starting test.service... machine # [ 58.301517] systemd[1]: Finished test.service. machine # [ 58.306887] systemd[1]: Reached target Local File Systems. machine # [ 58.309035] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 58.311179] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 58.313371] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 58.314873] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 58.317544] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 58.319131] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 58.322391] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 58.325891] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 58.327172] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 58.331586] systemd[1]: Reached target Remote File Systems. machine # [ 58.453175] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 58.454892] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 58.458528] systemd[1]: Starting linger-users.service... machine # [ 58.486781] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 58.487869] systemd[1]: Finished linger-users.service. machine # [ 58.745345] nixos[5039]: finished switching to system configuration /nix/store/7znq3n41vzi5ir6d7cz9wf3bm5bins1v-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceWithExtraSection/bin/switch-to-configuration test 2>&1, in 1.46 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceWithExtraSectionOtherName/bin/switch-to-configuration test 2>&1 machine # [ 58.973312] nixos[5181]: switching to system configuration /nix/store/20rmp27i256h4m2hba5iy29c2j558ivl-nixos-system-machine-test machine # [ 58.976771] systemd[1]: Stopped target Local File Systems. machine # [ 58.977748] systemd[1]: Stopped target Remote File Systems. machine # [ 59.199836] systemd[1]: Reload requested from client PID 5181 ('.switch-to-conf') (unit backdoor.service)... machine # [ 59.201437] systemd[1]: Reloading... machine # [ 59.440601] systemd[1]: Reloading finished in 238 ms. machine # [ 59.508567] systemd[628]: Reexecution requested from client PID 5250 ('switch-to-confi')... machine # [ 59.510240] systemd[628]: Reexecuting. machine # [ 59.531282] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.604717] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.610044] systemd[628]: Reload requested from client PID 5250 ('switch-to-confi')... machine # [ 59.611204] systemd[628]: Reloading... machine # [ 59.681945] systemd[628]: Reloading finished in 69 ms. machine # [ 59.687818] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.694772] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 59.695664] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 59.697223] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 59.716246] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 59.719247] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 59.720645] systemd[1]: Stopping Reactivate sysinit units... machine # [ 59.721704] systemd[1]: Reached target Reactivate sysinit units. machine # [ 59.723433] systemd[1]: test.service: Deactivated successfully. machine # [ 59.725122] systemd[1]: Stopped test.service. machine # [ 59.725693] systemd[1]: Stopping test.service... machine # [ 59.727504] systemd[1]: Starting test.service... machine # [ 59.741195] systemd[1]: Finished test.service. machine # [ 59.747072] systemd[1]: Reached target Local File Systems. machine # [ 59.749289] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 59.752453] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 59.753375] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 59.755425] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 59.759059] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 59.760626] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 59.762488] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 59.765677] systemd[1]: Reached target Remote File Systems. machine # [ 59.767297] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 59.768485] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 59.892171] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 59.894855] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 59.897937] systemd[1]: Starting linger-users.service... machine # [ 59.924458] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 59.925918] systemd[1]: Finished linger-users.service. machine # [ 60.183303] nixos[5181]: finished switching to system configuration /nix/store/20rmp27i256h4m2hba5iy29c2j558ivl-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceWithExtraSectionOtherName/bin/switch-to-configuration test 2>&1, in 1.44 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 60.397066] nixos[5323]: switching to system configuration /nix/store/hrg0892sd5m6szy0q1g0vjwv0ashwlyw-nixos-system-machine-test machine # [ 60.400501] systemd[1]: Stopped target Local File Systems. machine # [ 60.401793] systemd[1]: Stopped target Remote File Systems. machine # [ 60.602201] systemd[1]: Reload requested from client PID 5323 ('.switch-to-conf') (unit backdoor.service)... machine # [ 60.603800] systemd[1]: Reloading... machine # [ 60.835090] systemd[1]: Reloading finished in 229 ms. machine # [ 60.897439] systemd[628]: Reexecution requested from client PID 5392 ('switch-to-confi')... machine # [ 60.898941] systemd[628]: Reexecuting. machine # [ 60.919217] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 60.988719] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 60.994849] systemd[628]: Reload requested from client PID 5392 ('switch-to-confi')... machine # [ 60.997078] systemd[628]: Reloading... machine # [ 61.065910] systemd[628]: Reloading finished in 69 ms. machine # [ 61.071838] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 61.078839] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 61.079718] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 61.081463] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 61.100110] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 61.103566] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 61.104887] systemd[1]: Stopping Reactivate sysinit units... machine # [ 61.106143] systemd[1]: Reached target Reactivate sysinit units. machine # [ 61.107697] systemd[1]: test.service: Deactivated successfully. machine # [ 61.109244] systemd[1]: Stopped test.service. machine # [ 61.109847] systemd[1]: Stopping test.service... machine # [ 61.111798] systemd[1]: Starting test.service... machine # [ 61.125396] systemd[1]: Finished test.service. machine # [ 61.128803] systemd[1]: Reached target Local File Systems. machine # [ 61.130690] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 61.132756] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 61.134864] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 61.136550] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 61.139311] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 61.142121] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 61.143403] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 61.154312] systemd[1]: Reached target Remote File Systems. machine # [ 61.156830] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 61.158662] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 61.278361] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 61.282208] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 61.283988] systemd[1]: Starting linger-users.service... machine # [ 61.310460] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 61.311933] systemd[1]: Finished linger-users.service. machine # [ 61.569418] nixos[5323]: finished switching to system configuration /nix/store/hrg0892sd5m6szy0q1g0vjwv0ashwlyw-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 1.39 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceWithInstallSection/bin/switch-to-configuration test 2>&1 machine # [ 61.795290] nixos[5465]: switching to system configuration /nix/store/yzagjhq721620v1r4zc1g1w8ihc4hl1d-nixos-system-machine-test machine # [ 61.798817] systemd[1]: Stopped target Local File Systems. machine # [ 61.799822] systemd[1]: Stopped target Remote File Systems. machine # [ 62.020780] systemd[1]: Reload requested from client PID 5465 ('.switch-to-conf') (unit backdoor.service)... machine # [ 62.022426] systemd[1]: Reloading... machine # [ 62.261767] systemd[1]: Reloading finished in 237 ms. machine # [ 62.325913] systemd[628]: Reexecution requested from client PID 5534 ('switch-to-confi')... machine # [ 62.328423] systemd[628]: Reexecuting. machine # [ 62.348035] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.419744] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.425653] systemd[628]: Reload requested from client PID 5534 ('switch-to-confi')... machine # [ 62.427134] systemd[628]: Reloading... machine # [ 62.496819] systemd[628]: Reloading finished in 68 ms. machine # [ 62.501841] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.507871] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 62.509850] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 62.511288] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 62.530164] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 62.533065] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 62.534567] systemd[1]: Stopping Reactivate sysinit units... machine # [ 62.535664] systemd[1]: Reached target Reactivate sysinit units. machine # [ 62.536978] systemd[1]: Reached target Local File Systems. machine # [ 62.539436] systemd[1]: Reached target Remote File Systems. machine # [ 62.543368] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.545128] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 62.550160] systemd[1]: Starting linger-users.service... machine # [ 62.553203] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 62.558148] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 62.560129] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 62.561260] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 62.563233] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 62.564815] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.565988] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.607464] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 62.609514] systemd[1]: Finished linger-users.service. machine # [ 62.715585] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 62.717396] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 62.974586] nixos[5465]: finished switching to system configuration /nix/store/yzagjhq721620v1r4zc1g1w8ihc4hl1d-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceWithInstallSection/bin/switch-to-configuration test 2>&1, in 1.41 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceWithExtraKey/bin/switch-to-configuration test 2>&1 machine # [ 63.220611] nixos[5605]: switching to system configuration /nix/store/g9r9igdwg1knfvbr6a8am7f7l8klmrpp-nixos-system-machine-test machine # [ 63.223050] systemd[1]: Stopped target Remote File Systems. machine # [ 63.224688] systemd[1]: Stopped target Local File Systems. machine # [ 63.452601] systemd[1]: Reload requested from client PID 5605 ('.switch-to-conf') (unit backdoor.service)... machine # [ 63.454301] systemd[1]: Reloading... machine # [ 63.703838] systemd[1]: Reloading finished in 248 ms. machine # [ 63.768098] systemd[628]: Reexecution requested from client PID 5674 ('switch-to-confi')... machine # [ 63.769966] systemd[628]: Reexecuting. machine # [ 63.791359] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 63.864367] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 63.869587] systemd[628]: Reload requested from client PID 5674 ('switch-to-confi')... machine # [ 63.870990] systemd[628]: Reloading... machine # [ 63.944935] systemd[628]: Reloading finished in 73 ms. machine # [ 63.950837] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 63.957809] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 63.958751] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 63.960533] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 63.981634] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 63.984533] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 63.985926] systemd[1]: Stopping Reactivate sysinit units... machine # [ 63.986823] systemd[1]: Reached target Reactivate sysinit units. machine # [ 63.988496] systemd[1]: test.service: Deactivated successfully. machine # [ 63.990212] systemd[1]: Stopped test.service. machine # [ 63.990814] systemd[1]: Stopping test.service... machine # [ 63.992669] systemd[1]: Starting test.service... machine # [ 64.006848] systemd[1]: Finished test.service. machine # [ 64.010739] systemd[1]: Reached target Remote File Systems. machine # [ 64.013459] systemd[1]: Starting linger-users.service... machine # [ 64.021104] systemd[1]: Reached target Local File Systems. machine # [ 64.021819] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 64.025873] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 64.026632] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 64.027765] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 64.031191] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 64.034136] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 64.036546] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 64.040547] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 64.041844] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 64.075866] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 64.078058] systemd[1]: Finished linger-users.service. machine # [ 64.190791] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 64.192440] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 64.450469] nixos[5605]: finished switching to system configuration /nix/store/g9r9igdwg1knfvbr6a8am7f7l8klmrpp-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceWithExtraKey/bin/switch-to-configuration test 2>&1, in 1.48 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherValue/bin/switch-to-configuration test 2>&1 machine # [ 64.695800] nixos[5747]: switching to system configuration /nix/store/wmkzz8m2miam6c74by6sqkdh65v2falv-nixos-system-machine-test machine # [ 64.698261] systemd[1]: Stopped target Remote File Systems. machine # [ 64.700284] systemd[1]: Stopped target Local File Systems. machine # [ 64.931324] systemd[1]: Reload requested from client PID 5747 ('.switch-to-conf') (unit backdoor.service)... machine # [ 64.932903] systemd[1]: Reloading... machine # [ 65.184276] systemd[1]: Reloading finished in 250 ms. machine # [ 65.247729] systemd[628]: Reexecution requested from client PID 5816 ('switch-to-confi')... machine # [ 65.249832] systemd[628]: Reexecuting. machine # [ 65.271107] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.344685] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.350152] systemd[628]: Reload requested from client PID 5816 ('switch-to-confi')... machine # [ 65.351814] systemd[628]: Reloading... machine # [ 65.424739] systemd[628]: Reloading finished in 71 ms. machine # [ 65.429838] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.436102] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 65.438085] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 65.439555] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 65.461240] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 65.464391] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 65.465648] systemd[1]: Stopping Reactivate sysinit units... machine # [ 65.466595] systemd[1]: Reached target Reactivate sysinit units. machine # [ 65.468381] systemd[1]: test.service: Deactivated successfully. machine # [ 65.470119] systemd[1]: Stopped test.service. machine # [ 65.470717] systemd[1]: Stopping test.service... machine # [ 65.472448] systemd[1]: Starting test.service... machine # [ 65.486942] systemd[1]: Finished test.service. machine # [ 65.490318] systemd[1]: Reached target Remote File Systems. machine # [ 65.493991] systemd[1]: Reached target Local File Systems. machine # [ 65.494726] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 65.497186] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 65.500056] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 65.501320] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 65.503265] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 65.504663] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 65.507163] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 65.518798] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 65.520532] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 65.645199] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 65.646899] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 65.651164] systemd[1]: Starting linger-users.service... machine # [ 65.679443] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 65.680960] systemd[1]: Finished linger-users.service. machine # [ 65.937535] nixos[5747]: finished switching to system configuration /nix/store/wmkzz8m2miam6c74by6sqkdh65v2falv-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherValue/bin/switch-to-configuration test 2>&1, in 1.49 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherName/bin/switch-to-configuration test 2>&1 machine # [ 66.224572] nixos[5889]: switching to system configuration /nix/store/g4rdgqk45c74q4wkjaqm8fdl6b2g8jqc-nixos-system-machine-test machine # [ 66.227369] systemd[1]: Stopped target Local File Systems. machine # [ 66.229145] systemd[1]: Stopped target Remote File Systems. machine # [ 66.464595] systemd[1]: Reload requested from client PID 5889 ('.switch-to-conf') (unit backdoor.service)... machine # [ 66.466148] systemd[1]: Reloading... machine # [ 66.732850] systemd[1]: Reloading finished in 265 ms. machine # [ 66.794818] systemd[628]: Reexecution requested from client PID 5958 ('switch-to-confi')... machine # [ 66.797384] systemd[628]: Reexecuting. machine # [ 66.817978] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 66.889246] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 66.894278] systemd[628]: Reload requested from client PID 5958 ('switch-to-confi')... machine # [ 66.895866] systemd[628]: Reloading... machine # [ 66.965937] systemd[628]: Reloading finished in 69 ms. machine # [ 66.970839] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 66.976993] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 66.978930] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 66.980557] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 67.001110] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 67.004454] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 67.005479] systemd[1]: Stopping Reactivate sysinit units... machine # [ 67.006473] systemd[1]: Reached target Reactivate sysinit units. machine # [ 67.008243] systemd[1]: test.service: Deactivated successfully. machine # [ 67.010212] systemd[1]: Stopped test.service. machine # [ 67.010891] systemd[1]: Stopping test.service... machine # [ 67.012222] systemd[1]: Starting test.service... machine # [ 67.025584] systemd[1]: Finished test.service. machine # [ 67.029535] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 67.031375] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 67.035545] systemd[1]: Reached target Remote File Systems. machine # [ 67.039409] systemd[1]: Starting linger-users.service... machine # [ 67.045251] systemd[1]: Reached target Local File Systems. machine # [ 67.048101] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 67.052262] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 67.053364] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 67.055418] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 67.058322] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 67.059867] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 67.062261] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 67.095791] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 67.097704] systemd[1]: Finished linger-users.service. machine # [ 67.203197] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 67.204831] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 67.462649] nixos[5889]: finished switching to system configuration /nix/store/g4rdgqk45c74q4wkjaqm8fdl6b2g8jqc-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherName/bin/switch-to-configuration test 2>&1, in 1.52 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 67.669910] nixos[6031]: switching to system configuration /nix/store/hrg0892sd5m6szy0q1g0vjwv0ashwlyw-nixos-system-machine-test machine # [ 67.672474] systemd[1]: Stopped target Local File Systems. machine # [ 67.674241] systemd[1]: Stopped target Remote File Systems. machine # [ 67.875869] systemd[1]: Reload requested from client PID 6031 ('.switch-to-conf') (unit backdoor.service)... machine # [ 67.877479] systemd[1]: Reloading... machine # [ 68.107755] systemd[1]: Reloading finished in 228 ms. machine # [ 68.168280] systemd[628]: Reexecution requested from client PID 6100 ('switch-to-confi')... machine # [ 68.170829] systemd[628]: Reexecuting. machine # [ 68.192248] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.263422] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.268540] systemd[628]: Reload requested from client PID 6100 ('switch-to-confi')... machine # [ 68.270521] systemd[628]: Reloading... machine # [ 68.340941] systemd[628]: Reloading finished in 70 ms. machine # [ 68.346094] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.354064] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 68.354991] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 68.356783] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 68.376235] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 68.379579] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 68.380785] systemd[1]: Stopping Reactivate sysinit units... machine # [ 68.381955] systemd[1]: Reached target Reactivate sysinit units. machine # [ 68.383349] systemd[1]: test.service: Deactivated successfully. machine # [ 68.385256] systemd[1]: Stopped test.service. machine # [ 68.385866] systemd[1]: Stopping test.service... machine # [ 68.387546] systemd[1]: Starting test.service... machine # [ 68.402489] systemd[1]: Finished test.service. machine # [ 68.405543] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 68.407317] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 68.410406] systemd[1]: Reached target Local File Systems. machine # [ 68.412270] systemd[1]: Reached target Remote File Systems. machine # [ 68.413966] systemd[1]: Starting linger-users.service... machine # [ 68.423202] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 68.427371] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 68.428707] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 68.430588] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 68.433611] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 68.436238] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 68.438468] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 68.471323] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 68.473092] systemd[1]: Finished linger-users.service. machine # [ 68.582749] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 68.584581] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 68.841290] nixos[6031]: finished switching to system configuration /nix/store/hrg0892sd5m6szy0q1g0vjwv0ashwlyw-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 1.38 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceReloadTrigger/bin/switch-to-configuration test 2>&1 machine # [ 69.071238] nixos[6173]: switching to system configuration /nix/store/mihz78md02bidj8b7xz3da1hmk8x1md3-nixos-system-machine-test machine # [ 69.073537] systemd[1]: Stopped target Remote File Systems. machine # [ 69.075245] systemd[1]: Stopped target Local File Systems. machine # [ 69.300396] systemd[1]: Reload requested from client PID 6173 ('.switch-to-conf') (unit backdoor.service)... machine # [ 69.302532] systemd[1]: Reloading... machine # [ 69.544693] systemd[1]: Reloading finished in 240 ms. machine # [ 69.606217] systemd[628]: Reexecution requested from client PID 6242 ('switch-to-confi')... machine # [ 69.608077] systemd[628]: Reexecuting. machine # [ 69.627030] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.697863] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.703072] systemd[628]: Reload requested from client PID 6242 ('switch-to-confi')... machine # [ 69.704529] systemd[628]: Reloading... machine # [ 69.775772] systemd[628]: Reloading finished in 70 ms. machine # [ 69.781836] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.788280] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 69.790055] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 69.791765] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 69.811362] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 69.814451] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 69.815794] systemd[1]: Stopping Reactivate sysinit units... machine # [ 69.816785] systemd[1]: Reached target Reactivate sysinit units. machine # [ 69.819993] systemd[1]: Reloading test.service... machine # [ 69.833401] systemd[1]: Reloaded test.service. machine # [ 69.837604] systemd[1]: Reached target Remote File Systems. machine # [ 69.838594] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 69.840138] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 69.845043] systemd[1]: Reached target Local File Systems. machine # [ 69.845812] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 69.849149] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 69.849953] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 69.851723] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 69.853758] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 69.855207] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 69.856421] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 69.984963] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 69.986647] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 69.990629] systemd[1]: Starting linger-users.service... machine # [ 70.017875] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 70.019360] systemd[1]: Finished linger-users.service. machine # [ 70.276807] nixos[6173]: finished switching to system configuration /nix/store/mihz78md02bidj8b7xz3da1hmk8x1md3-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 1.44 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModified/bin/switch-to-configuration test 2>&1 machine # [ 70.513586] nixos[6315]: switching to system configuration /nix/store/zap2yny1jgkacnr3h6lgfy8hw3yd3p33-nixos-system-machine-test machine # [ 70.516088] systemd[1]: Stopped target Local File Systems. machine # [ 70.518214] systemd[1]: Stopped target Remote File Systems. machine # [ 70.747148] systemd[1]: Reload requested from client PID 6315 ('.switch-to-conf') (unit backdoor.service)... machine # [ 70.748828] systemd[1]: Reloading... machine # [ 70.998831] systemd[1]: Reloading finished in 248 ms. machine # [ 71.061287] systemd[628]: Reexecution requested from client PID 6384 ('switch-to-confi')... machine # [ 71.063702] systemd[628]: Reexecuting. machine # [ 71.084142] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 71.158650] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 71.163851] systemd[628]: Reload requested from client PID 6384 ('switch-to-confi')... machine # [ 71.165581] systemd[628]: Reloading... machine # [ 71.239211] systemd[628]: Reloading finished in 72 ms. machine # [ 71.244906] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 71.250821] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 71.252316] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 71.254230] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 71.273913] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 71.276871] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 71.278187] systemd[1]: Stopping Reactivate sysinit units... machine # [ 71.278931] systemd[1]: Reached target Reactivate sysinit units. machine # [ 71.282442] systemd[1]: Reloading test.service... machine # [ 71.295853] systemd[1]: Reloaded test.service. machine # [ 71.300142] systemd[1]: Reached target Local File Systems. machine # [ 71.302064] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 71.305439] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 71.306864] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 71.308208] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 71.310176] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 71.311642] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 71.312805] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 71.317391] systemd[1]: Reached target Remote File Systems. machine # [ 71.322288] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 71.324229] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 71.452191] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 71.454068] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 71.457884] systemd[1]: Starting linger-users.service... machine # [ 71.486054] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 71.488674] systemd[1]: Finished linger-users.service. machine # [ 71.745132] nixos[6315]: finished switching to system configuration /nix/store/zap2yny1jgkacnr3h6lgfy8hw3yd3p33-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModified/bin/switch-to-configuration test 2>&1, in 1.47 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedAndSomethingElse/bin/switch-to-configuration test 2>&1 machine # [ 71.994987] nixos[6457]: switching to system configuration /nix/store/xv31w7khnr34k44d0m9izgjfw0n7x00b-nixos-system-machine-test machine # [ 71.997559] systemd[1]: Stopped target Local File Systems. machine # [ 71.999410] systemd[1]: Stopped target Remote File Systems. machine # [ 72.220822] systemd[1]: Reload requested from client PID 6457 ('.switch-to-conf') (unit backdoor.service)... machine # [ 72.222675] systemd[1]: Reloading... machine # [ 72.462299] systemd[1]: Reloading finished in 238 ms. machine # [ 72.523361] systemd[628]: Reexecution requested from client PID 6526 ('switch-to-confi')... machine # [ 72.525948] systemd[628]: Reexecuting. machine # [ 72.546972] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 72.617690] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 72.623928] systemd[628]: Reload requested from client PID 6526 ('switch-to-confi')... machine # [ 72.625477] systemd[628]: Reloading... machine # [ 72.694677] systemd[628]: Reloading finished in 68 ms. machine # [ 72.698836] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 72.705148] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 72.707062] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 72.708668] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 72.728223] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 72.731433] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 72.732688] systemd[1]: Stopping Reactivate sysinit units... machine # [ 72.733722] systemd[1]: Reached target Reactivate sysinit units. machine # [ 72.735371] systemd[1]: test.service: Deactivated successfully. machine # [ 72.737263] systemd[1]: Stopped test.service. machine # [ 72.737900] systemd[1]: Stopping test.service... machine # [ 72.739438] systemd[1]: Starting test.service... machine # [ 72.753296] systemd[1]: Finished test.service. machine # [ 72.756858] systemd[1]: Reached target Local File Systems. machine # [ 72.759066] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 72.761290] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 72.763137] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 72.765468] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 72.767671] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 72.769287] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 72.772201] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 72.778326] systemd[1]: Reached target Remote File Systems. machine # [ 72.783348] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 72.785141] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 72.905754] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 72.907466] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 72.911221] systemd[1]: Starting linger-users.service... machine # [ 72.938614] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 72.940137] systemd[1]: Finished linger-users.service. machine # [ 73.197592] nixos[6457]: finished switching to system configuration /nix/store/xv31w7khnr34k44d0m9izgjfw0n7x00b-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedAndSomethingElse/bin/switch-to-configuration test 2>&1, in 1.45 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedSomethingElse/bin/switch-to-configuration test 2>&1 machine # [ 73.375593] nixos[6599]: switching to system configuration /nix/store/g9r9igdwg1knfvbr6a8am7f7l8klmrpp-nixos-system-machine-test machine # [ 73.379119] systemd[1]: Stopped target Remote File Systems. machine # [ 73.380359] systemd[1]: Stopped target Local File Systems. machine # [ 73.574955] systemd[1]: Reload requested from client PID 6599 ('.switch-to-conf') (unit backdoor.service)... machine # [ 73.576632] systemd[1]: Reloading... machine # [ 73.811294] systemd[1]: Reloading finished in 233 ms. machine # [ 73.868331] systemd[628]: Reexecution requested from client PID 6668 ('switch-to-confi')... machine # [ 73.870122] systemd[628]: Reexecuting. machine # [ 73.890861] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 73.962514] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 73.967717] systemd[628]: Reload requested from client PID 6668 ('switch-to-confi')... machine # [ 73.969539] systemd[628]: Reloading... machine # [ 74.042431] systemd[628]: Reloading finished in 71 ms. machine # [ 74.048891] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 74.055127] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 74.057039] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 74.058690] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 74.077475] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 74.080512] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 74.081946] systemd[1]: Stopping Reactivate sysinit units... machine # [ 74.082961] systemd[1]: Reached target Reactivate sysinit units. machine # [ 74.085447] systemd[1]: Reached target Local File Systems. machine # [ 74.087551] systemd[1]: Reached target Remote File Systems. machine # [ 74.090991] systemd[1]: Starting linger-users.service... machine # [ 74.092659] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 74.094587] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 74.101990] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 74.105761] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 74.106729] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 74.108959] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 74.111503] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 74.113878] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 74.116302] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 74.155481] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 74.157231] systemd[1]: Finished linger-users.service. machine # [ 74.265158] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 74.267902] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 74.523630] nixos[6599]: finished switching to system configuration /nix/store/g9r9igdwg1knfvbr6a8am7f7l8klmrpp-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedSomethingElse/bin/switch-to-configuration test 2>&1, in 1.33 seconds) (finished: subtest: unit file parser, in 18.61 seconds) subtest: restart and reload by activation script machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1 machine # [ 74.711703] nixos[6739]: switching to system configuration /nix/store/kkqqq6mdswcwxmqdw5gr8ygx0bk8ay5a-nixos-system-machine-test machine # [ 74.714489] systemd[1]: Stopped target Remote File Systems. machine # [ 74.715879] systemd[1]: Stopped target Local File Systems. machine # [ 74.923476] systemd[1]: Reload requested from client PID 6739 ('.switch-to-conf') (unit backdoor.service)... machine # [ 74.925145] systemd[1]: Reloading... machine # [ 75.154763] systemd[1]: Reloading finished in 228 ms. machine # [ 75.221679] systemd[628]: Reexecution requested from client PID 6808 ('switch-to-confi')... machine # [ 75.223702] systemd[628]: Reexecuting. machine # [ 75.242026] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 75.314485] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 75.319541] systemd[628]: Reload requested from client PID 6808 ('switch-to-confi')... machine # [ 75.321278] systemd[628]: Reloading... machine # [ 75.390815] systemd[628]: Reloading finished in 68 ms. machine # [ 75.396853] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 75.402971] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 75.404192] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 75.406778] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 75.426168] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 75.429343] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 75.430801] systemd[1]: Stopping Reactivate sysinit units... machine # [ 75.431865] systemd[1]: Reached target Reactivate sysinit units. machine # [ 75.434315] systemd[1]: Reached target Remote File Systems. machine # [ 75.437140] systemd[1]: Starting linger-users.service... machine # [ 75.439391] systemd[1]: Reached target Local File Systems. machine # [ 75.449464] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 75.451482] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 75.454185] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 75.458058] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 75.458951] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 75.462097] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 75.464354] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 75.465985] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 75.470400] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 75.505393] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 75.507833] systemd[1]: Finished linger-users.service. machine # [ 75.613269] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 75.615792] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 75.873286] nixos[6739]: finished switching to system configuration /nix/store/kkqqq6mdswcwxmqdw5gr8ygx0bk8ay5a-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1, in 1.35 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1 machine # [ 76.104082] nixos[6879]: switching to system configuration /nix/store/0vi4khn0s8fm71g2spbdjh8v38nkfs6w-nixos-system-machine-test machine # [ 76.106421] systemd[1]: test.service: Deactivated successfully. machine # [ 76.108644] systemd[1]: Stopped test.service. machine # [ 76.109346] systemd[1]: Stopped target Local File Systems. machine # [ 76.110720] systemd[1]: Stopped target Remote File Systems. machine # [ 76.325453] systemd[1]: Reload requested from client PID 6879 ('.switch-to-conf') (unit backdoor.service)... machine # [ 76.327237] systemd[1]: Reloading... machine # [ 76.577279] systemd[1]: Reloading finished in 249 ms. machine # [ 76.638280] systemd[628]: Reexecution requested from client PID 6950 ('switch-to-confi')... machine # [ 76.640579] systemd[628]: Reexecuting. machine # [ 76.661978] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 76.732449] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 76.737532] systemd[628]: Reload requested from client PID 6950 ('switch-to-confi')... machine # [ 76.739881] systemd[628]: Reloading... machine # [ 76.808494] systemd[628]: Reloading finished in 69 ms. machine # [ 76.814822] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 76.821062] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 76.821988] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 76.824544] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 76.843242] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 76.846302] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 76.847527] systemd[1]: Stopping Reactivate sysinit units... machine # [ 76.848561] systemd[1]: Reached target Reactivate sysinit units. machine # [ 76.855297] systemd[1]: Starting reload-triggers-and-restart-by-as.service... machine # [ 76.861742] systemd[1]: Starting simple-restart-service.service... machine # [ 76.862638] systemd[1]: Reached target Local File Systems. machine # [ 76.864717] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 76.867122] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 76.877455] systemd[1]: Starting no-restart-service.service... machine # [ 76.878854] systemd[1]: Reached target Remote File Systems. machine # [ 76.888422] systemd[1]: Created slice Slice /system/templated-no-restart-service. machine # [ 76.892234] systemd[1]: Starting templated-no-restart-service@instance.service... machine # [ 76.899189] systemd[1]: Starting simple-reload-service.service... machine # [ 76.907065] systemd[1]: Created slice Slice /system/templated-reload-triggers-and-restart-by-as. machine # [ 76.912071] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 76.918419] systemd[1]: Created slice Slice /system/templated-simple-restart-service. machine # [ 76.926672] systemd[1]: Starting templated-simple-restart-service@instance.service... machine # [ 76.929144] systemd[1]: Starting simple-service.service... machine # [ 76.941731] systemd[1]: Created slice Slice /system/templated-reload-triggers. machine # [ 76.942819] systemd[1]: Created slice Slice /system/templated-reload-triggers-and-restart. machine # [ 76.949180] systemd[1]: Starting linger-users.service... machine # [ 76.951160] systemd[1]: Starting reload-triggers-and-restart.service... machine # [ 76.957072] systemd[1]: Starting reload-triggers.service... machine # [ 76.967515] systemd[1]: Starting templated-reload-triggers-and-restart@instance.service... machine # [ 76.968725] systemd[1]: Starting templated-reload-triggers@instance.service... machine # [ 76.972177] systemd[1]: Finished reload-triggers-and-restart-by-as.service. machine # [ 76.983868] systemd[1]: Finished simple-restart-service.service. machine # [ 76.996527] systemd[1]: Created slice Slice /system/templated-simple-reload-service. machine # [ 76.997633] systemd[1]: Created slice Slice /system/templated-simple-service. machine # [ 77.008442] systemd[1]: Starting templated-simple-reload-service@instance.service... machine # [ 77.009554] systemd[1]: Starting templated-simple-service@instance.service... machine # [ 77.023381] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 77.024876] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 77.033471] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 77.038158] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 77.042300] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 77.047083] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 77.050977] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 77.060614] systemd[1]: Finished no-restart-service.service. machine # [ 77.064346] systemd[1]: Finished templated-no-restart-service@instance.service. machine # [ 77.087363] systemd[1]: Finished simple-reload-service.service. machine # [ 77.088469] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 77.111360] systemd[1]: Finished templated-simple-restart-service@instance.service. machine # [ 77.117267] systemd[1]: Finished simple-service.service. machine # [ 77.123186] systemd[1]: Finished reload-triggers-and-restart.service. machine # [ 77.129424] systemd[1]: Finished reload-triggers.service. machine # [ 77.134368] systemd[1]: Finished templated-reload-triggers-and-restart@instance.service. machine # [ 77.139600] systemd[1]: Finished templated-reload-triggers@instance.service. machine # [ 77.149843] systemd[1]: Finished templated-simple-reload-service@instance.service. machine # [ 77.153111] systemd[1]: Finished templated-simple-service@instance.service. machine # [ 77.167864] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 77.169573] systemd[1]: Finished linger-users.service. machine # [ 77.282445] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 77.284221] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 77.540060] nixos[6879]: finished switching to system configuration /nix/store/0vi4khn0s8fm71g2spbdjh8v38nkfs6w-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1, in 1.66 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1 machine # [ 77.772302] nixos[7049]: switching to system configuration /nix/store/0vi4khn0s8fm71g2spbdjh8v38nkfs6w-nixos-system-machine-test machine # [ 77.775590] systemd[1]: Stopped target Remote File Systems. machine # [ 77.776597] systemd[1]: Stopped target Local File Systems. machine # [ 78.006555] systemd[1]: Reload requested from client PID 7049 ('.switch-to-conf') (unit backdoor.service)... machine # [ 78.008224] systemd[1]: Reloading... machine # [ 78.257149] systemd[1]: Reloading finished in 246 ms. machine # [ 78.321307] systemd[628]: Reexecution requested from client PID 7120 ('switch-to-confi')... machine # [ 78.322578] systemd[628]: Reexecuting. machine # [ 78.343643] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 78.414343] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 78.419297] systemd[628]: Reload requested from client PID 7120 ('switch-to-confi')... machine # [ 78.420848] systemd[628]: Reloading... machine # [ 78.487746] systemd[628]: Reloading finished in 65 ms. machine # [ 78.492852] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 78.498853] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 78.499802] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 78.502181] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 78.520374] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 78.523785] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 78.524789] systemd[1]: Stopping Reactivate sysinit units... machine # [ 78.525752] systemd[1]: Reached target Reactivate sysinit units. machine # [ 78.529866] systemd[1]: Reloading reload-triggers-and-restart.service... machine # [ 78.535389] systemd[1]: Reloading reload-triggers.service... machine # [ 78.540033] systemd[1]: Reloading simple-reload-service.service... machine # [ 78.542078] systemd[1]: Reloading templated-reload-triggers-and-restart@instance.service... machine # [ 78.550170] systemd[1]: Reloading templated-reload-triggers@instance.service... machine # [ 78.554077] systemd[1]: Reloading templated-simple-reload-service@instance.service... machine # [ 78.596527] systemd[1]: Reloaded reload-triggers.service. machine # [ 78.600076] systemd[1]: Reloaded reload-triggers-and-restart.service. machine # [ 78.600896] systemd[1]: Reloaded simple-reload-service.service. machine # [ 78.605913] systemd[1]: Reloaded templated-reload-triggers-and-restart@instance.service. machine # [ 78.611111] systemd[1]: Reloaded templated-simple-reload-service@instance.service. machine # [ 78.613343] systemd[1]: Reloaded templated-reload-triggers@instance.service. machine # [ 78.616802] systemd[1]: reload-triggers-and-restart-by-as.service: Deactivated successfully. machine # [ 78.618273] systemd[1]: Stopped reload-triggers-and-restart-by-as.service. machine # [ 78.620135] systemd[1]: Stopping reload-triggers-and-restart-by-as.service... machine # [ 78.621596] systemd[1]: Starting reload-triggers-and-restart-by-as.service... machine # [ 78.623262] systemd[1]: simple-restart-service.service: Deactivated successfully. machine # [ 78.624946] systemd[1]: Stopped simple-restart-service.service. machine # [ 78.625894] systemd[1]: Stopping simple-restart-service.service... machine # [ 78.627335] systemd[1]: Starting simple-restart-service.service... machine # [ 78.631154] systemd[1]: simple-service.service: Deactivated successfully. machine # [ 78.632140] systemd[1]: Stopped simple-service.service. machine # [ 78.632819] systemd[1]: Stopping simple-service.service... machine # [ 78.635062] systemd[1]: Starting simple-service.service... machine # [ 78.639344] systemd[1]: templated-reload-triggers-and-restart-by-as@instance.service: Deactivated successfully. machine # [ 78.640667] systemd[1]: Stopped templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 78.641722] systemd[1]: Stopping templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 78.643629] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 78.649055] systemd[1]: templated-simple-restart-service@instance.service: Deactivated successfully. machine # [ 78.650206] systemd[1]: Stopped templated-simple-restart-service@instance.service. machine # [ 78.651180] systemd[1]: Stopping templated-simple-restart-service@instance.service... machine # [ 78.656196] systemd[1]: Starting templated-simple-restart-service@instance.service... machine # [ 78.657424] systemd[1]: templated-simple-service@instance.service: Deactivated successfully. machine # [ 78.660259] systemd[1]: Stopped templated-simple-service@instance.service. machine # [ 78.661170] systemd[1]: Stopping templated-simple-service@instance.service... machine # [ 78.662090] systemd[1]: Starting templated-simple-service@instance.service... machine # [ 78.702529] systemd[1]: Finished simple-restart-service.service. machine # [ 78.706297] systemd[1]: Finished reload-triggers-and-restart-by-as.service. machine # [ 78.708223] systemd[1]: Finished simple-service.service. machine # [ 78.716557] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 78.718867] systemd[1]: Finished templated-simple-restart-service@instance.service. machine # [ 78.720909] systemd[1]: Finished templated-simple-service@instance.service. machine # [ 78.726201] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 78.728366] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 78.734511] systemd[1]: Reached target Remote File Systems. machine # [ 78.737457] systemd[1]: Starting linger-users.service... machine # [ 78.739068] systemd[1]: Reached target Local File Systems. machine # [ 78.745621] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 78.750347] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 78.751686] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 78.753637] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 78.756482] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 78.759105] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 78.760408] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 78.791469] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 78.793184] systemd[1]: Finished linger-users.service. machine # [ 78.903727] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 78.906753] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 79.162530] nixos[7049]: finished switching to system configuration /nix/store/0vi4khn0s8fm71g2spbdjh8v38nkfs6w-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1, in 1.63 seconds) machine: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script-modified/bin/switch-to-configuration test 2>&1 machine # [ 79.435595] nixos[7215]: switching to system configuration /nix/store/fgmz8yswdfcbc52c97pzbm2h0m8mskci-nixos-system-machine-test machine # [ 79.437671] systemd[1]: Stopped target Remote File Systems. machine # [ 79.439354] systemd[1]: Stopped target Local File Systems. machine # [ 79.674863] systemd[1]: Reload requested from client PID 7215 ('.switch-to-conf') (unit backdoor.service)... machine # [ 79.676432] systemd[1]: Reloading... machine # [ 79.935846] systemd[1]: Reloading finished in 258 ms. machine # [ 80.001850] systemd[628]: Reexecution requested from client PID 7286 ('switch-to-confi')... machine # [ 80.004477] systemd[628]: Reexecuting. machine # [ 80.025065] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 80.097416] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 80.102829] systemd[628]: Reload requested from client PID 7286 ('switch-to-confi')... machine # [ 80.104941] systemd[628]: Reloading... machine # [ 80.176326] systemd[628]: Reloading finished in 71 ms. machine # [ 80.181872] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 80.188621] systemd[628]: Stopped Run user-specific NixOS activation. machine # [ 80.190070] systemd[628]: Stopping Run user-specific NixOS activation... machine # [ 80.192102] systemd[628]: Starting Run user-specific NixOS activation... machine # [ 80.210706] systemd[628]: Finished Run user-specific NixOS activation. machine # [ 80.213596] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 80.215205] systemd[1]: Stopping Reactivate sysinit units... machine # [ 80.215984] systemd[1]: Reached target Reactivate sysinit units. machine # [ 80.219909] systemd[1]: Reloading reload-triggers.service... machine # [ 80.225363] systemd[1]: Reloading simple-reload-service.service... machine # [ 80.231156] systemd[1]: Reloading templated-reload-triggers@instance.service... machine # [ 80.234070] systemd[1]: Reloading templated-simple-reload-service@instance.service... machine # [ 80.267228] systemd[1]: Reloaded templated-reload-triggers@instance.service. machine # [ 80.271410] systemd[1]: Reloaded reload-triggers.service. machine # [ 80.272581] systemd[1]: Reloaded simple-reload-service.service. machine # [ 80.274575] systemd[1]: Reloaded templated-simple-reload-service@instance.service. machine # [ 80.278938] systemd[1]: reload-triggers-and-restart-by-as.service: Deactivated successfully. machine # [ 80.280403] systemd[1]: Stopped reload-triggers-and-restart-by-as.service. machine # [ 80.282167] systemd[1]: Stopping reload-triggers-and-restart-by-as.service... machine # [ 80.284471] systemd[1]: Starting reload-triggers-and-restart-by-as.service... machine # [ 80.286294] systemd[1]: reload-triggers-and-restart.service: Deactivated successfully. machine # [ 80.288305] systemd[1]: Stopped reload-triggers-and-restart.service. machine # [ 80.289319] systemd[1]: Stopping reload-triggers-and-restart.service... machine # [ 80.291398] systemd[1]: Starting reload-triggers-and-restart.service... machine # [ 80.294825] systemd[1]: simple-restart-service.service: Deactivated successfully. machine # [ 80.295772] systemd[1]: Stopped simple-restart-service.service. machine # [ 80.296562] systemd[1]: Stopping simple-restart-service.service... machine # [ 80.298792] systemd[1]: Starting simple-restart-service.service... machine # [ 80.301575] systemd[1]: simple-service.service: Deactivated successfully. machine # [ 80.304149] systemd[1]: Stopped simple-service.service. machine # [ 80.304848] systemd[1]: Stopping simple-service.service... machine # [ 80.309878] systemd[1]: Starting simple-service.service... machine # [ 80.310627] systemd[1]: templated-reload-triggers-and-restart-by-as@instance.service: Deactivated successfully. machine # [ 80.311926] systemd[1]: Stopped templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 80.313023] systemd[1]: Stopping templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 80.315624] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service... machine # [ 80.319211] systemd[1]: templated-reload-triggers-and-restart@instance.service: Deactivated successfully. machine # [ 80.323420] systemd[1]: Stopped templated-reload-triggers-and-restart@instance.service. machine # [ 80.324496] systemd[1]: Stopping templated-reload-triggers-and-restart@instance.service... machine # [ 80.327209] systemd[1]: Starting templated-reload-triggers-and-restart@instance.service... machine # [ 80.330149] systemd[1]: templated-simple-restart-service@instance.service: Deactivated successfully. machine # [ 80.331478] systemd[1]: Stopped templated-simple-restart-service@instance.service. machine # [ 80.337173] systemd[1]: Stopping templated-simple-restart-service@instance.service... machine # [ 80.338219] systemd[1]: Starting templated-simple-restart-service@instance.service... machine # [ 80.342231] systemd[1]: templated-simple-service@instance.service: Deactivated successfully. machine # [ 80.345141] systemd[1]: Stopped templated-simple-service@instance.service. machine # [ 80.348705] systemd[1]: Stopping templated-simple-service@instance.service... machine # [ 80.349754] systemd[1]: Starting templated-simple-service@instance.service... machine # [ 80.398085] systemd[1]: Finished simple-service.service. machine # [ 80.402722] systemd[1]: Finished reload-triggers-and-restart-by-as.service. machine # [ 80.403835] systemd[1]: Finished reload-triggers-and-restart.service. machine # [ 80.408289] systemd[1]: Finished simple-restart-service.service. machine # [ 80.417095] systemd[1]: Finished templated-reload-triggers-and-restart@instance.service. machine # [ 80.418647] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service. machine # [ 80.424304] systemd[1]: Finished templated-simple-restart-service@instance.service. machine # [ 80.428324] systemd[1]: Finished templated-simple-service@instance.service. machine # [ 80.433951] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 80.435622] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 80.438855] systemd[1]: Reached target Remote File Systems. machine # [ 80.441691] systemd[1]: Starting linger-users.service... machine # [ 80.443558] systemd[1]: Reached target Local File Systems. machine # [ 80.447486] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 80.452971] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 80.453943] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 80.455095] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 80.456985] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 80.458398] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 80.459568] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 80.500677] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 80.502473] systemd[1]: Finished linger-users.service. machine # [ 80.596625] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 80.598430] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 80.853724] nixos[7215]: finished switching to system configuration /nix/store/fgmz8yswdfcbc52c97pzbm2h0m8mskci-nixos-system-machine-test machine: (finished: must succeed: /nix/store/awln1xsmxayy7n1ilffh60m5218azfcj-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/awln1xsmxayy7n1ilffh60m5218azfcj-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/awln1xsmxayy7n1ilffh60m5218azfcj-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration dry-activate 2>&1, in 0.25 seconds) (finished: subtest: restart and reload by activation script, in 6.58 seconds) (finished: run the VM test script, in 82.11 seconds) test script finished in 82.18s cleanup kill QemuMachine (pid 45) machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/lb41b0anx1f98y9y5s9mdv97gjgsq740-python3-3.14.7/bin/python3.14) machine # [2026-10-19T03:01:33Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-10-19T03:01:33Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-10-19T03:01:33Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.17 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