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.ZEc5uRS2r7', 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: c40c0b43-e5c6-4e39-9f94-9d4e148ce33a 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:14Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-10-19T03:00:14Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-10-19T03:00:14Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-10-19T03:00:14Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-10-19T03:00:14Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-10-19T03:00:14Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-10-19T03:00:14Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-10-19T03:00:14Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-10-19T03:00:14Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-10-19T03:00:14Z INFO virtiofsd] Client connected, servicing requests machine # [2026-10-19T03:00:14Z INFO virtiofsd] Client connected, servicing requests machine # [2026-10-19T03:00:14Z 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/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/init regInfo=/nix/.ro-store/kyssj46vp4das5clhh4kkvijqqkjyah0-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 466685489 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.000766] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 machine # [ 0.000794] 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.002684] found SMP MP-table at [mem 0x000f5450-0x000f545f] machine # [ 0.002697] Using GB pages for direct mapping machine # [ 0.002798] RAMDISK: [mem 0x3e2b5000-0x3ffcffff] machine # [ 0.002806] ACPI: Early table checksum verification disabled machine # [ 0.002809] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) machine # [ 0.002813] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002817] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002824] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002827] ACPI: FACS 0x000000003FFE0000 000040 machine # [ 0.002828] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002830] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002831] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002833] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002834] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d] machine # [ 0.002835] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219] machine # [ 0.002836] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f] machine # [ 0.002836] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385] machine # [ 0.002837] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd] machine # [ 0.002837] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9] machine # [ 0.002838] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421] machine # [ 0.003068] No NUMA configuration found machine # [ 0.003069] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff] machine # [ 0.003072] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff] machine # [ 0.005460] Zone ranges: machine # [ 0.005461] DMA [mem 0x0000000000001000-0x0000000000ffffff] machine # [ 0.005463] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff] machine # [ 0.005464] Normal empty machine # [ 0.005465] Device empty machine # [ 0.005466] Movable zone start for each node machine # [ 0.005467] Early memory node ranges machine # [ 0.005467] node 0: [mem 0x0000000000001000-0x000000000009efff] machine # [ 0.005468] node 0: [mem 0x0000000000100000-0x000000003ffd7fff] machine # [ 0.005469] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff] machine # [ 0.005492] On node 0, zone DMA: 1 pages in unavailable ranges machine # [ 0.005765] On node 0, zone DMA: 97 pages in unavailable ranges machine # [ 0.024494] On node 0, zone DMA32: 40 pages in unavailable ranges machine # [ 0.024944] ACPI: PM-Timer IO Port: 0x608 machine # [ 0.024954] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) machine # [ 0.024983] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 machine # [ 0.024985] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) machine # [ 0.024987] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) machine # [ 0.024988] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) machine # [ 0.024989] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) machine # [ 0.024990] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) machine # [ 0.024992] ACPI: Using ACPI (MADT) for SMP configuration information machine # [ 0.024993] ACPI: HPET id: 0x8086a201 base: 0xfed00000 machine # [ 0.024997] TSC deadline timer available machine # [ 0.025001] CPU topo: Max. logical packages: 1 machine # [ 0.025002] CPU topo: Max. logical dies: 1 machine # [ 0.025002] CPU topo: Max. dies per package: 1 machine # [ 0.025005] CPU topo: Max. threads per core: 1 machine # [ 0.025006] CPU topo: Num. cores per package: 1 machine # [ 0.025006] CPU topo: Num. threads per package: 1 machine # [ 0.025007] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs machine # [ 0.025023] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() machine # [ 0.025050] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] machine # [ 0.025052] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] machine # [ 0.025053] [mem 0x40000000-0xafffffff] available for PCI devices machine # [ 0.025055] Booting paravirtualized kernel on KVM machine # [ 0.025058] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns machine # [ 0.029732] setup_percpu: NR_CPUS:512 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 machine # [ 0.032020] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152 machine # [ 0.032072] kvm-guest: PV spinlocks disabled, single CPU machine # [ 0.032074] 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/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/init regInfo=/nix/.ro-store/kyssj46vp4das5clhh4kkvijqqkjyah0-closure-info/registration console=ttyS0,115200n8 console=tty0 machine # [ 0.032169] Unknown kernel command line parameters "regInfo=/nix/.ro-store/kyssj46vp4das5clhh4kkvijqqkjyah0-closure-info/registration", will be passed to user space. machine # [ 0.032181] random: crng init done machine # [ 0.032182] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes machine # [ 0.032204] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.032764] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.032793] Fallback order for Node 0: 0 machine # [ 0.032796] Built 1 zonelists, mobility grouping on. Total pages: 262006 machine # [ 0.032797] Policy zone: DMA32 machine # [ 0.035068] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.037751] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 machine # [ 0.040505] allocated 2097152 bytes of page_ext machine # [ 0.050825] ftrace: allocating 48842 entries in 192 pages machine # [ 0.050827] ftrace: allocated 192 pages with 2 groups machine # [ 0.051720] Dynamic Preempt: lazy machine # [ 0.051897] rcu: Preemptible hierarchical RCU implementation. machine # [ 0.051897] rcu: RCU event tracing is enabled. machine # [ 0.051898] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1. machine # [ 0.051900] Trampoline variant of Tasks RCU enabled. machine # [ 0.051901] Rude variant of Tasks RCU enabled. machine # [ 0.051901] Tracing variant of Tasks RCU enabled. machine # [ 0.051902] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. machine # [ 0.051902] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 machine # [ 0.051925] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.051927] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.051928] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.056327] NR_IRQS: 33024, nr_irqs: 256, preallocated irqs: 16 machine # [ 0.056596] rcu: srcu_init: Setting srcu_struct sizes based on contention. machine # [ 0.056603] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns machine # [ 0.056707] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.060277] Console: colour VGA+ 80x25 machine # [ 0.060279] printk: legacy console [tty0] enabled machine # [ 0.089731] printk: legacy console [ttyS0] enabled machine # [ 0.196269] ACPI: Core revision 20250807 machine # [ 0.197209] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns machine # [ 0.198813] APIC: Switch to symmetric I/O mode setup machine # [ 0.199847] x2apic enabled machine # [ 0.200613] APIC: Switched APIC routing to: physical x2apic machine # [ 0.202554] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 machine # [ 0.203631] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22983f10a64, max_idle_ns: 440795218721 ns machine # [ 0.205405] Calibrating delay loop (skipped) preset value.. 4800.01 BogoMIPS (lpj=2400008) machine # [ 0.206492] x86/cpu: User Mode Instruction Prevention (UMIP) activated machine # [ 0.207524] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 machine # [ 0.208403] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 machine # [ 0.209407] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto machine # [ 0.210402] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl machine # [ 0.211403] Transient Scheduler Attacks: Vulnerable: No microcode machine # [ 0.212402] Spectre V2 : Mitigation: Enhanced / Automatic IBRS machine # [ 0.213402] Speculative Return Stack Overflow: Mitigation: Safe RET machine # [ 0.214402] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization machine # [ 0.216410] Spectre V2 : Enabling IBPB for BPF machine # [ 0.217403] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier machine # [ 0.219403] active return thunk: srso_alias_return_thunk machine # [ 0.220329] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' machine # [ 0.221402] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' machine # [ 0.222402] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' machine # [ 0.223402] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' machine # [ 0.224402] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' machine # [ 0.225402] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' machine # [ 0.226402] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' machine # [ 0.227402] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 machine # [ 0.229219] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 machine # [ 0.230224] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 machine # [ 0.231402] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 machine # [ 0.232402] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 machine # [ 0.233402] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format. machine # [ 0.267967] Freeing SMP alternatives memory: 48K machine # [ 0.268406] pid_max: default: 32768 minimum: 301 machine # [ 0.269534] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.270504] landlock: Up and running. machine # [ 0.271403] Yama: becoming mindful. machine # [ 0.272251] LSM support for eBPF active machine # [ 0.273174] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.274433] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.276846] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1) machine # [ 0.277989] Performance Events: Fam17h+ core perfctr, AMD PMU driver. machine # [ 0.278411] ... version: 2 machine # [ 0.279165] ... bit width: 48 machine # [ 0.279405] ... generic counters: 6 machine # [ 0.280132] ... generic bitmap: 000000000000003f machine # [ 0.280405] ... fixed-purpose counters: 0 machine # [ 0.281128] ... fixed-purpose bitmap: 0000000000000000 machine # [ 0.281405] ... value mask: 0000ffffffffffff machine # [ 0.282358] ... max period: 00007fffffffffff machine # [ 0.283152] ... global_ctrl mask: 000000000000003f machine # [ 0.283504] signal: max sigframe size: 3376 machine # [ 0.284347] rcu: Hierarchical SRCU implementation. machine # [ 0.285042] rcu: Max phase no-delay instances is 400. machine # [ 0.290082] smp: Bringing up secondary CPUs ... machine # [ 0.290420] smp: Brought up 1 node, 1 CPU machine # [ 0.291145] smpboot: Total of 1 processors activated (4800.01 BogoMIPS) machine # [ 0.291599] Memory: 940292K/1048024K available (18132K kernel code, 2734K rwdata, 13652K rodata, 3660K init, 3620K bss, 100324K reserved, 0K cma-reserved) machine # [ 0.292633] devtmpfs: initialized machine # [ 0.293458] x86/mm: Memory block size: 128MB machine # [ 0.295189] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.296412] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.297506] pinctrl core: initialized pinctrl subsystem machine # [ 0.298634] PM: RTC time: 03:00:14, date: 2026-10-19 machine # [ 0.302480] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.303779] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.304423] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.305558] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.306415] audit: initializing netlink subsys (disabled) machine # [ 0.307646] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.307649] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.308407] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.309408] audit: type=2000 audit(1792378815.231:1): state=initialized audit_enabled=0 res=1 machine # [ 0.311407] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.311408] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.312420] cpuidle: using governor menu machine # [ 0.314687] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 machine # [ 0.315752] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] machine # [ 0.316408] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry machine # [ 0.317417] PCI: Using configuration type 1 for base access machine # [ 0.318621] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. machine # [ 0.323491] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.324406] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.329405] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.330404] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.340775] ACPI: Added _OSI(Module Device) machine # [ 0.341406] ACPI: Added _OSI(Processor Device) machine # [ 0.342183] ACPI: Added _OSI(Processor Aggregator Device) machine # [ 0.350590] ACPI: 1 ACPI AML tables successfully acquired and loaded machine # [ 0.354819] ACPI: Interpreter enabled machine # [ 0.355421] ACPI: PM: (supports S0 S3 S4 S5) machine # [ 0.356157] ACPI: Using IOAPIC for interrupt routing machine # [ 0.358488] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug machine # [ 0.359406] PCI: Using E820 reservations for host bridge windows machine # [ 0.362547] ACPI: Enabled 2 GPEs in block 00 to 3F machine # [ 0.368843] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) machine # [ 0.369411] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] machine # [ 0.370482] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] machine # [ 0.371529] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] machine # [ 0.372759] PCI host bridge to bus 0000:00 machine # [ 0.373409] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] machine # [ 0.374406] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] machine # [ 0.375405] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] machine # [ 0.376405] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window] machine # [ 0.377405] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] machine # [ 0.378405] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] machine # [ 0.379405] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.380434] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.381844] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint machine # [ 0.384537] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] machine # [ 0.385418] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] machine # [ 0.386473] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] machine # [ 0.387875] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] machine # [ 0.389144] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.391423] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f] machine # [ 0.392306] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] machine # [ 0.393187] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] machine # [ 0.394279] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] machine # [ 0.396196] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.398415] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f] machine # [ 0.399329] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] machine # [ 0.400188] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] machine # [ 0.401857] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.404412] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f] machine # [ 0.405411] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] machine # [ 0.406427] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] machine # [ 0.407981] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.409440] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f] machine # [ 0.410362] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] machine # [ 0.411231] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref] machine # [ 0.412315] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref] machine # [ 0.413775] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.415418] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] machine # [ 0.416426] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref] machine # [ 0.418031] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.420263] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] machine # [ 0.421219] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref] machine # [ 0.422980] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.424445] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] machine # [ 0.425453] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref] machine # [ 0.426947] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.428418] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] machine # [ 0.429404] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref] machine # [ 0.430943] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.433274] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf] machine # [ 0.434076] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] machine # [ 0.434428] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref] machine # [ 0.435977] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.437438] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f] machine # [ 0.438341] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff] machine # [ 0.439251] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref] machine # [ 0.440892] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.442115] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f] machine # [ 0.442611] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.444098] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf] machine # [ 0.444639] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.446071] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df] machine # [ 0.446723] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.449078] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff] machine # [ 0.450421] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint machine # [ 0.451703] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO machine # [ 0.452669] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint machine # [ 0.454422] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff] machine # [ 0.455397] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff] machine # [ 0.456566] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint machine # [ 0.458076] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] machine # [ 0.461470] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 machine # [ 0.462510] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 machine # [ 0.463505] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 machine # [ 0.464500] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 machine # [ 0.465688] ACPI: PCI: Interrupt link LNKE configured for IRQ 10 machine # [ 0.466513] ACPI: PCI: Interrupt link LNKF configured for IRQ 10 machine # [ 0.467504] ACPI: PCI: Interrupt link LNKG configured for IRQ 11 machine # [ 0.468505] ACPI: PCI: Interrupt link LNKH configured for IRQ 11 machine # [ 0.469445] ACPI: PCI: Interrupt link GSIA configured for IRQ 16 machine # [ 0.470418] ACPI: PCI: Interrupt link GSIB configured for IRQ 17 machine # [ 0.471421] ACPI: PCI: Interrupt link GSIC configured for IRQ 18 machine # [ 0.472420] ACPI: PCI: Interrupt link GSID configured for IRQ 19 machine # [ 0.473422] ACPI: PCI: Interrupt link GSIE configured for IRQ 20 machine # [ 0.474416] ACPI: PCI: Interrupt link GSIF configured for IRQ 21 machine # [ 0.475416] ACPI: PCI: Interrupt link GSIG configured for IRQ 22 machine # [ 0.476417] ACPI: PCI: Interrupt link GSIH configured for IRQ 23 machine # [ 0.478469] iommu: Default domain type: Translated machine # [ 0.479349] iommu: DMA domain TLB invalidation policy: lazy mode machine # [ 0.480487] ACPI: bus type USB registered machine # [ 0.481278] usbcore: registered new interface driver usbfs machine # [ 0.482141] usbcore: registered new interface driver hub machine # [ 0.482412] usbcore: registered new device driver usb machine # [ 0.484163] NetLabel: Initializing machine # [ 0.484412] NetLabel: domain hash size = 128 machine # [ 0.485264] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO machine # [ 0.486233] NetLabel: unlabeled traffic allowed by default machine # [ 0.487179] PCI: Using ACPI for IRQ routing machine # [ 0.531704] pci 0000:00:01.0: vgaarb: setting as boot VGA device machine # [ 0.532401] pci 0000:00:01.0: vgaarb: bridge control possible machine # [ 0.532401] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none machine # [ 0.532408] vgaarb: loaded machine # [ 0.533234] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 machine # [ 0.534020] hpet0: 3 comparators, 64-bit 100.000000 MHz counter machine # [ 0.538494] clocksource: Switched to clocksource kvm-clock machine # [ 0.542244] VFS: Disk quotas dquot_6.6.0 machine # [ 0.542953] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.544358] pnp: PnP ACPI init machine # [ 0.545210] ACPI: IRQ 4 override to edge(!), high(!) machine # [ 0.546210] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved machine # [ 0.547831] pnp: PnP ACPI: found 5 devices machine # [ 0.555477] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns machine # [ 0.556982] clocksource: Switched to clocksource acpi_pm machine # [ 0.557998] NET: Registered PF_INET protocol family machine # [ 0.559080] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.576429] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.578038] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.579430] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.580833] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.582169] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.583390] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.584744] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.585937] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.587198] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.588227] NET: Registered PF_XDP protocol family machine # [ 0.589113] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] machine # [ 0.590187] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] machine # [ 0.591261] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] machine # [ 0.592445] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window] machine # [ 0.593614] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] machine # [ 0.594881] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] machine # [ 0.596760] ACPI: \_SB_.GSIA: Enabled at IRQ 16 machine # [ 0.599487] ACPI: \_SB_.GSIB: Enabled at IRQ 17 machine # [ 0.601856] ACPI: \_SB_.GSIC: Enabled at IRQ 18 machine # [ 0.603916] ACPI: \_SB_.GSID: Enabled at IRQ 19 machine # [ 0.605717] PCI: CLS 0 bytes, default 64 machine # [ 0.606779] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22983f10a64, max_idle_ns: 440795218721 ns machine # [ 0.608603] Trying to unpack rootfs image as initramfs... machine # [ 0.654257] Initialise system trusted keyrings machine # [ 0.655238] workingset: timestamp_bits=40 max_order=18 bucket_order=0 machine # [ 0.674624] Key type asymmetric registered machine # [ 0.679836] Asymmetric key parser 'x509' registered machine # [ 0.680719] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) machine # [ 0.683935] io scheduler mq-deadline registered machine # [ 0.684747] io scheduler kyber registered machine # [ 0.688378] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.689736] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A machine # [ 0.694977] Linux agpgart interface v0.103 machine # [ 0.695775] ACPI: bus type drm_connector registered machine # [ 0.699077] usbcore: registered new interface driver usbserial_generic machine # [ 0.700222] usbserial: USB Serial support registered for generic machine # [ 0.702833] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS. machine # [ 0.702833] Please enable it if your BIOS has the CPPC option. machine # [ 0.705165] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled machine # [ 0.709951] drop_monitor: Initializing network drop monitor service machine # [ 0.711193] NET: Registered PF_INET6 protocol family machine # [ 0.714296] Segment Routing with IPv6 machine # [ 0.716848] In-situ OAM (IOAM) with IPv6 machine # [ 0.717949] IPI shorthand broadcast: enabled machine # [ 0.726288] sched_clock: Marking stable (582015167, 143798592)->(802241122, -76427363) machine # [ 0.732074] registered taskstats version 1 machine # [ 0.733134] Loading compiled-in X.509 certificates machine # [ 0.751821] Demotion targets for Node 0: null machine # [ 0.752730] Key type .fscrypt registered machine # [ 0.755818] Key type fscrypt-provisioning registered machine # [ 0.756841] ima: No TPM chip found, activating TPM-bypass! machine # [ 0.757782] ima: Allocated hash algorithm: sha1 machine # [ 0.759854] ima: No architecture policies found machine # [ 0.762826] PM: Magic number: 14:728:7 machine # [ 0.764490] RAS: Correctable Errors collector initialized. machine # [ 0.773587] clk: Disabling unused clocks machine # [ 0.776833] PM: genpd: Disabling unused power domains machine # [ 0.950078] Freeing initrd memory: 29804K machine # [ 0.953503] Freeing unused decrypted memory: 2028K machine # [ 0.956359] Freeing unused kernel image (initmem) memory: 3660K machine # [ 0.957476] Write protecting the kernel read-only data: 32768k machine # [ 0.958924] Freeing unused kernel image (text/rodata gap) memory: 296K machine # [ 0.960481] Freeing unused kernel image (rodata/data gap) memory: 684K machine # [ 1.011421] x86/mm: Checked W+X mappings: passed, no W+X pages found. machine # [ 1.012598] Run /init as init process machine # [ 1.023717] systemd[1]: Inserted module 'autofs4' machine # [ 1.044255] fuse: init (API version 7.45) machine # [ 1.051562] ACPI: \_SB_.GSIG: Enabled at IRQ 22 machine # [ 1.054153] ACPI: \_SB_.GSIH: Enabled at IRQ 23 machine # [ 1.057229] ACPI: \_SB_.GSIE: Enabled at IRQ 20 machine # [ 1.059234] ACPI: \_SB_.GSIF: Enabled at IRQ 21 machine # [ 1.064728] virtiofs virtio5: discovered new tag: nix-store machine # [ 1.066295] virtiofs virtio5: virtio_fs_setup_dax: No cache capability machine # [ 1.073150] virtiofs virtio6: discovered new tag: shared machine # [ 1.074884] virtiofs virtio6: virtio_fs_setup_dax: No cache capability machine # [ 1.078089] virtiofs virtio7: discovered new tag: xchg machine # [ 1.079570] virtiofs virtio7: virtio_fs_setup_dax: No cache capability machine # [ 1.098512] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.100294] 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.105708] systemd[1]: Detected virtualization kvm. machine # [ 1.106627] systemd[1]: Detected architecture x86-64. machine # [ 1.107559] systemd[1]: Running in initrd. machine # [ 1.108537] systemd[1]: Initializing machine ID from random generator. machine # [ 1.109746] systemd[1]: Hostname set to . machine # [ 1.180340] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.216237] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.220058] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.221257] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.222703] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.223861] systemd[1]: Reached target Path Units. machine # [ 1.224763] systemd[1]: Reached target Slice Units. machine # [ 1.225692] systemd[1]: Reached target Swaps. machine # [ 1.226531] systemd[1]: Reached target Timer Units. machine # [ 1.227545] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.228876] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.230088] systemd[1]: Listening on Journal Sockets. machine # [ 1.231094] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.232120] systemd[1]: Reached target Socket Units. machine # [ 1.233906] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.237892] systemd[1]: Starting Load Kernel Module configfs... machine # [ 1.246884] systemd[1]: Starting Journal Service... machine # [ 1.256935] systemd[1]: Starting Load Kernel Modules... machine # [ 1.272942] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.277520] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.291180] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.294484] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 1.303277] systemd[1]: Finished Load Kernel Module configfs. machine # [ 1.309282] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config machine # [ 1.314446] systemd-journald[65]: Collecting audit messages is disabled. machine # [ 1.321091] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.329926] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.340972] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.362913] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.372018] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.383345] systemd[1]: Finished Load Kernel Modules. machine # [ 1.392018] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.410921] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.417014] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.421913] systemd[1]: Reached target Local File Systems. machine # [ 1.429345] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.288142] systemd-modules-load[66]: Inserted module 'dm_mod' machine # [ 1.289458] systemd-modules-load[66]: Inserted module 'virtio_balloon' machine # [ 1.291272] systemd-modules-load[66]: Inserted module 'virtio_gpu' machine # [ 1.441105] systemd[1]: Started Journal Service. machine # [ 1.304205] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.315467] systemd[1]: Starting Create System Files and Directories... machine # [ 1.342396] systemd[1]: Finished Create System Files and Directories. machine # [ 1.351430] systemd-udevd[73]: Using default interface naming scheme 'v261'. machine # [ 1.375069] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 1.431098] systemd[1]: Finished Coldplug All udev Devices. machine # [ 1.432259] systemd[1]: Reached target System Initialization. machine # [ 1.433205] systemd[1]: Reached target Basic System. machine # [ 1.720878] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 machine # [ 1.729580] serio: i8042 KBD port at 0x60,0x64 irq 1 machine # [ 1.737909] virtio_blk virtio2: 1/0/0 default/read/poll queues machine # [ 1.740822] serio: i8042 AUX port at 0x60,0x64 irq 12 machine # [ 1.743145] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 1.765050] ehci-pci 0000:00:1d.7: EHCI Host Controller machine # [ 1.765955] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1 machine # [ 1.767162] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000 machine # [ 1.774126] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 machine # [ 1.776160] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18 machine # [ 1.777301] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 1.780818] usb usb1: Product: EHCI Host Controller machine # [ 1.781518] usb usb1: Manufacturer: Linux 6.18.56 ehci_hcd machine # [ 1.783591] usb usb1: SerialNumber: 0000:00:1d.7 machine # [ 1.784998] hub 1-0:1.0: USB hub found machine # [ 1.786292] hub 1-0:1.0: 6 ports detected machine # [ 1.790836] uhci_hcd 0000:00:1d.0: UHCI Host Controller machine # [ 1.791599] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2 machine # [ 1.802170] uhci_hcd 0000:00:1d.0: detected 2 ports machine # [ 1.809772] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c180 machine # [ 1.816055] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 1.817175] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 1.832510] SCSI subsystem initialized machine # [ 1.690502] systemd[1]: Starting Virtual Console Setup... machine # [ 1.835866] usb usb2: Product: UHCI Host Controller machine # [ 1.836577] usb usb2: Manufacturer: Linux 6.18.56 uhci_hcd machine # [ 1.702717] (udev-worker)[85]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 1.706830] (udev-worker)[85]: Network interface NamePolicy= disabled on kernel command line. machine # [ 1.852834] usb usb2: SerialNumber: 0000:00:1d.0 machine # [ 1.860331] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 machine # [ 1.867898] hub 2-0:1.0: USB hub found machine # [ 1.870407] hub 2-0:1.0: 2 ports detected machine # [ 1.733294] systemd-vconsole-setup[97]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 1.737579] systemd[1]: Finished Virtual Console Setup. machine # [ 1.896396] uhci_hcd 0000:00:1d.1: UHCI Host Controller machine # [ 1.897184] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3 machine # [ 1.755312] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 1.757214] systemd[1]: Reached target Initrd Root Device. machine # [ 1.759464] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 1.910017] uhci_hcd 0000:00:1d.1: detected 2 ports machine # [ 1.911200] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a0 machine # [ 1.915333] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 1.916532] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 1.926413] usb usb3: Product: UHCI Host Controller machine # [ 1.927422] usb usb3: Manufacturer: Linux 6.18.56 uhci_hcd machine # [ 1.928349] usb usb3: SerialNumber: 0000:00:1d.1 machine # [ 1.930902] hub 3-0:1.0: USB hub found machine # [ 1.932138] hub 3-0:1.0: 2 ports detected machine # [ 1.937274] uhci_hcd 0000:00:1d.2: UHCI Host Controller machine # [ 1.939944] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4 machine # [ 1.943897] uhci_hcd 0000:00:1d.2: detected 2 ports machine # [ 1.803316] systemd-fsck[103]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 1.948182] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c0 machine # [ 1.950150] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 1.809805] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 1.960007] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 1.961191] usb usb4: Product: UHCI Host Controller machine # [ 1.962887] usb usb4: Manufacturer: Linux 6.18.56 uhci_hcd machine # [ 1.963643] usb usb4: SerialNumber: 0000:00:1d.2 machine # [ 1.966176] hub 4-0:1.0: USB hub found machine # [ 1.968870] hub 4-0:1.0: 2 ports detected machine # [ 1.984530] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode machine # [ 1.995624] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) machine # [ 1.997025] ahci 0000:00:1f.2: flags: 64bit ncq only machine # [ 2.003991] scsi host0: ahci machine # [ 2.005465] scsi host1: ahci machine # [ 2.006646] scsi host2: ahci machine # [ 2.008226] scsi host3: ahci machine # [ 2.009549] scsi host4: ahci machine # [ 2.010656] scsi host5: ahci machine # [ 2.011994] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 1 machine # [ 2.013284] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 1 machine # [ 2.014432] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 1 machine # [ 2.015601] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 1 machine # [ 2.016749] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 1 machine # [ 2.017907] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 1 machine # [ 2.025856] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 2.155092] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 machine # [ 2.157915] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 machine # [ 2.161080] usb 1-1: Product: QEMU USB Tablet machine # [ 2.162853] usb 1-1: Manufacturer: QEMU machine # [ 2.164438] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1 machine # [ 2.196206] hid: raw HID events driver (C) Jiri Kosina machine # [ 2.146720] systemd[1]: Mounting /sysroot... machine # [ 2.327971] ata6: SATA link down (SStatus 0 SControl 300) machine # [ 2.330106] ata5: SATA link down (SStatus 0 SControl 300) machine # [ 2.331785] ata1: SATA link down (SStatus 0 SControl 300) machine # [ 2.333509] ata4: SATA link down (SStatus 0 SControl 300) machine # [ 2.335212] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) machine # [ 2.336948] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 machine # [ 2.342597] ata3.00: applying bridge limits machine # [ 2.344244] ata3.00: configured for UDMA/100 machine # [ 2.345863] ata2: SATA link down (SStatus 0 SControl 300) machine # [ 2.348032] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 machine # [ 2.387826] usbcore: registered new interface driver usbhid machine # [ 2.388602] usbhid: USB HID core driver machine # [ 2.414373] 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.422877] 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.434886] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray machine # [ 2.457028] cdrom: Uniform CD-ROM driver Revision: 3.20 machine # [ 2.469355] EXT4-fs (vda): mounted filesystem c40c0b43-e5c6-4e39-9f94-9d4e148ce33a r/w with ordered data mode. Quota mode: none. machine # [ 2.329575] systemd[1]: Mounted /sysroot. machine # [ 2.332273] systemd[1]: Reached target Initrd Root File System. machine # [ 2.335153] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 2.346136] systemd-sysroot-fstab-check[138]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 2.351041] systemd[1]: Reload requested from client PID 138 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 2.352557] systemd[1]: Reloading... machine # [ 2.421717] systemd[1]: Reloading finished in 71 ms. machine # [ 2.430080] systemd-sysroot-fstab-check[138]: Requesting initrd-fs.target/start/replace... machine # [ 2.434170] systemd-sysroot-fstab-check[138]: Requesting swap.target/start/replace... machine # [ 2.437351] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 2.438724] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 2.440252] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 3.146748] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.149736] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 3.154757] systemd[1]: Mounting /sysroot/run... machine # [ 3.156720] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.162150] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.185915] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.188314] systemd[1]: Mounted /sysroot/run. machine # [ 3.191902] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.195807] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.196758] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.200419] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.210356] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.211910] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.213890] systemd[1]: Mounting /sysroot/nix/store... machine # [ 3.235379] systemd[1]: Mounted /sysroot/nix/store. machine # [ 3.236472] systemd[1]: Reached target Initrd File Systems. machine # [ 3.238090] systemd[1]: Starting Find NixOS closure... machine # [ 3.240601] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 3.259199] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 3.266056] systemd[1]: Finished Find NixOS closure. machine # [ 3.267384] systemd[1]: Reached target Initrd Default Target. machine # [ 3.269124] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 3.283250] systemd[1]: Stopped target Initrd Default Target. machine # [ 3.284144] systemd[1]: Stopped target Basic System. machine # [ 3.284881] systemd[1]: Stopped target Initrd Root Device. machine # [ 3.285682] systemd[1]: Stopped target Path Units. machine # [ 3.286352] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 3.287389] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 3.288753] systemd[1]: Stopped target Slice Units. machine # [ 3.290237] systemd[1]: Stopped target Socket Units. machine # [ 3.291032] systemd[1]: Stopped target System Initialization. machine # [ 3.291806] systemd[1]: Stopped target Swaps. machine # [ 3.292677] systemd[1]: Stopped target Timer Units. machine # [ 3.293591] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 3.295159] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 3.296026] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 3.297097] systemd[1]: Stopped Find NixOS closure. machine # [ 3.298191] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.299263] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 3.301244] systemd[1]: Stopped Apply Kernel Variables. machine # [ 3.302251] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 3.303495] systemd[1]: Stopped Load Kernel Modules. machine # [ 3.305039] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 3.306077] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 3.307224] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 3.308766] systemd[1]: Stopped Create System Files and Directories. machine # [ 3.309619] systemd[1]: Stopped target Local File Systems. machine # [ 3.310704] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 3.312166] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 3.313537] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 3.315809] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 3.316969] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 3.318109] systemd[1]: Stopped Virtual Console Setup. machine # [ 3.325311] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 3.330292] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 3.331340] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 3.333171] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 3.338814] systemd[1]: Starting Cleanup udev Database... machine # [ 3.339682] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 3.340795] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 3.342967] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 3.344122] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 3.345188] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 3.346209] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 3.347121] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.349121] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.363506] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 3.366067] systemd[1]: Finished Cleanup udev Database. machine # [ 3.366823] systemd[1]: Reached target Switch Root. machine # [ 3.368329] systemd[1]: Starting NixOS Activation... machine # [ 3.437796] initrd-nixos-activation-start[189]: booting system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test machine # [ 3.465467] initrd-nixos-activation-start[189]: running activation script... machine # [ 3.643198] initrd-nixos-activation-start[212]: setting up /etc... machine # [ 3.741495] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 3.743162] systemd[1]: Finished NixOS Activation. machine # [ 3.744660] systemd[1]: Starting Switch Root... machine # [ 3.758203] systemd[1]: Switching root. machine # [ 3.922302] systemd-journald[65]: Received SIGTERM from PID 1 (systemd). machine # [ 3.981368] NET: Registered PF_VSOCK protocol family machine # [ 4.005283] 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 # [ 4.009410] systemd[1]: Detected virtualization kvm. machine # [ 4.010093] systemd[1]: Detected architecture x86-64. machine # [ 4.010845] systemd[1]: Detected first boot. machine # [ 4.012480] systemd[1]: Initializing machine ID from random generator. machine # [ 4.102687] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 4.213289] systemd[1]: Applying preset policy. machine # [ 4.383091] systemd[1]: Populated /etc with preset unit settings. machine # [ 4.557328] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 4.558663] systemd[1]: Stopped initrd-switch-root.service. machine # [ 4.561171] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 4.563288] systemd[1]: Created slice Slice /system/getty. machine # [ 4.564628] systemd[1]: Created slice User and Session Slice. machine # [ 4.565575] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 4.566851] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 4.567947] systemd[1]: Expecting device /dev/hvc0... machine # [ 4.568692] systemd[1]: Expecting device /dev/ttyS0... machine # [ 4.569472] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 4.570307] systemd[1]: Stopped target initrd-fs.target. machine # [ 4.571042] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 4.571870] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 4.572722] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 4.573640] systemd[1]: Reached target Path Units. machine # [ 4.574329] systemd[1]: Reached target Remote File Systems. machine # [ 4.575136] systemd[1]: Reached target Slice Units. machine # [ 4.575874] systemd[1]: Reached target Swaps. machine # [ 4.577506] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 4.591532] systemd[1]: Listening on Process Core Dump Socket. machine # [ 4.593168] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 4.594828] systemd[1]: Listening on Factory Reset Management. machine # [ 4.595724] systemd[1]: Listening on Hostname Service Socket. machine # [ 4.598564] systemd[1]: Starting Journal Log Access Socket... machine # [ 4.600049] systemd[1]: Listening on Journal Audit Socket. machine # [ 4.602717] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 4.603867] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 4.605014] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 4.606298] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 4.611991] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 4.613163] systemd[1]: Listening on udev Varlink Socket. machine # [ 4.615637] systemd[1]: Mounting Huge Pages File System... machine # [ 4.618150] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 4.625984] systemd[1]: Mounting Kernel Debug File System... machine # [ 4.633754] systemd[1]: Mounting Kernel Trace File System... machine # [ 4.643930] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 4.653843] systemd[1]: Starting Load Kernel Module configfs... machine # [ 4.658464] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 4.670183] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 4.684246] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 4.694861] systemd[1]: Mounting FUSE Control File System... machine # [ 4.695760] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 4.713348] systemd[1]: Starting Journal Service... machine # [ 4.718656] systemd[1]: Starting Load Kernel Modules... machine # [ 4.731343] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 4.743450] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 4.747941] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 4.758076] systemd[1]: Starting Coldplug All udev Devices... machine # [ 4.766729] systemd[1]: Listening on Journal Log Access Socket. machine # [ 4.769424] systemd-journald[283]: Collecting audit messages is enabled. machine # [ 4.774913] systemd[1]: Mounted Huge Pages File System. machine # [ 4.782012] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 4.786173] systemd[1]: Mounted Kernel Debug File System. machine # [ 4.790075] systemd[1]: Mounted Kernel Trace File System. machine # [ 4.647740] systemd[1]: Queued start job for default target Multi-User System. machine # [ 4.650295] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0. machine # [ 4.795576] systemd[1]: Started Journal Service. machine # [ 4.652775] systemd[1]: Unnecessary job was removed for /sys/devices/pnp0/00:03/00:03:0/00:03:0.0/tty/ttyS0. machine # [ 4.654362] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 4.663748] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 4.666484] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 4.672343] systemd[1]: Finished Load Kernel Module configfs. machine # [ 4.818355] loop: module loaded machine # [ 4.677692] systemd-modules-load[284]: Inserted module 'loop' machine # [ 4.832960] EXT4-fs (vda): re-mounted c40c0b43-e5c6-4e39-9f94-9d4e148ce33a. machine # [ 4.691588] systemd[1]: Mounted FUSE Control File System. machine # [ 4.694100] systemd[1]: Finished Load Kernel Modules. machine # [ 4.700628] systemd[1]: Mounting Kernel Configuration File System... machine # [ 4.706130] systemd[1]: Starting Firewall... machine # [ 4.712261] systemd[1]: Starting Apply Kernel Variables... machine # [ 4.720580] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 4.725517] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 4.731087] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 4.738260] systemd-oomd[286]: No swap; memory pressure usage will be degraded machine # [ 4.749062] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 4.749952] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 4.763075] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 4.763981] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 4.779809] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 4.979108] systemd-journald[283]: Received client request to flush runtime journal. machine # [ 4.914720] systemd[1]: Mounted Kernel Configuration File System. machine # [ 4.916364] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 4.921268] systemd[1]: Reached target First Boot Complete. machine # [ 4.922165] systemd[1]: Finished Apply Kernel Variables. machine # [ 4.925394] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 4.942102] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 4.944804] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 5.024525] systemd[1]: Finished Coldplug All udev Devices. machine # [ 5.043416] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 5.044576] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 5.047302] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 5.092432] systemd-udevd[323]: Using default interface naming scheme 'v261'. machine # [ 5.135498] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 5.199881] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 5.267385] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 5.286514] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped. machine # [ 5.325354] (udev-worker)[349]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 5.327751] (udev-worker)[349]: Network interface NamePolicy= disabled on kernel command line. machine # [ 5.417792] systemd[1]: Mounting /run/wrappers... machine # [ 5.422082] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 5.423126] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 5.424164] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 5.425457] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 5.427438] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 5.428986] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 5.432333] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 5.471950] systemd[1]: Mounted /run/wrappers. machine # [ 5.473474] systemd[1]: Reached target Local File Systems. machine # [ 5.477341] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 5.481378] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 5.482250] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 5.487077] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 5.492635] systemd[1]: Starting Create System Files and Directories... machine # [ 5.581265] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 5.586795] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 5.620428] systemd[1]: Finished Create System Files and Directories. machine # [ 5.627209] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 5.634599] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 5.795863] mousedev: PS/2 mouse device common for all mice machine # [ 5.801737] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console machine # [ 5.724447] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 5.734763] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 5.741862] systemd[1]: Starting Update is Completed... machine # [ 5.787645] systemd[1]: Finished Update is Completed. machine # [ 5.809077] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 machine # [ 5.821024] ACPI: button: Power Button [PWRF] machine # [ 5.985053] rtc_cmos PNP0B00:00: RTC can wake from S4 machine # [ 6.006143] rtc_cmos PNP0B00:00: registered as rtc0 machine # [ 5.972390] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 5.974734] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 5.976094] systemd[1]: Reached target System Initialization. machine # [ 5.978263] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 5.979239] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 5.981168] systemd[1]: Reached target Timer Units. machine # [ 5.981911] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 5.983983] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 5.986163] systemd[1]: Reached target Socket Units. machine # [ 5.987073] systemd[1]: Reached target Basic System. machine # [ 5.990483] systemd[1]: Started backdoor.service. machine # [ 5.994073] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 6.000166] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 6.006212] rtc_cmos PNP0B00:00: setting system clock to 2026-10-19T03:00:20 UTC (1792378820) machine # [ 6.006323] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs machine # [ 6.007070] systemd-journald[283]: Time jumped backwards, rotating. machine # [ 6.011210] systemd[1]: Starting Post-Boot Actions... machine # [ 6.023478] systemd[1]: Started Reset console on configuration changes. machine # [ 6.042143] systemd[1]: Starting resolvconf update... machine # [ 6.053896] systemd[1]: Finished Firewall. machine # [ 6.020251] parport_pc 00:02: reported by Plug and Play ACPI machine # [ 6.020336] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] machine # [ 6.032675] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized machine # [ 6.081603] systemd[1]: Starting D-Bus System Message Bus... machine # connecting to host... machine # [ 6.129338] nsncd[493]: Oct 19 03:00:20.766 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 6.137110] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 6.149058] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 6.153177] systemd[1]: Reached target User and Group Name Lookups. machine # [ 6.166199] systemd[1]: Starting User Login Management... machine # [ 6.170619] systemd[1]: Finished Post-Boot Actions. machine # [ 6.068122] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input4 machine # [ 6.084478] Console: switching to colour dummy device 80x25 machine: Guest shell says: b'Spawning backdoor root shell...\n' machine: connected to guest root shell machine: (connecting took 6.87 seconds) machine: (finished: waiting for the VM to finish booting, in 7.22 seconds) machine # [ 6.195802] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 6.254107] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt machine # [ 6.246526] dbus-broker-launch[502]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 6.254228] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD machine # [ 6.395749] [drm] Found bochs VGA, ID 0xb0c5. machine # [ 6.395751] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. machine # [ 6.254355] dbus-broker-launch[502]: NSS returned no entry for 'systemd-timesync' machine # [ 6.255378] dbus-broker-launch[502]: Invalid user-name in /nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 6.437887] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic machine # [ 6.438577] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0 machine # [ 6.449558] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6 machine # [ 6.457442] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5 machine # [ 6.344243] dbus-broker-launch[502]: Ready machine # [ 6.356934] systemd-logind[521]: New seat seat0. machine # [ 6.377078] systemd-logind[521]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) machine # [ 6.383166] systemd-logind[521]: Watching system buttons on /dev/input/event2 (Power Button) machine # [ 6.414629] systemd[1]: Started D-Bus System Message Bus. machine # [ 6.418069] systemd[1]: Started User Login Management. machine # [ 6.549439] Console: switching to colour frame buffer device 160x50 machine # [ 6.448085] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 6.450176] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 6.451083] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 6.451904] systemd[1]: Stopping User and Group Name Lookups... machine # [ 6.459135] systemd[1]: Starting linger-users.service... machine # [ 6.463416] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 6.608349] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device machine # [ 6.619205] ppdev: user-space parallel port driver machine # [ 6.476869] systemd[1]: Starting Virtual Console Setup... machine # [ 6.481292] systemd[1]: nscd.service: Deactivated successfully. machine # [ 6.499176] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 6.549819] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 6.719677] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660) machine # [ 6.578150] systemd[1]: Finished resolvconf update. machine # [ 6.601605] systemd[1]: Created slice Slice /user/1001. machine # [ 6.602387] systemd[1]: Reached target Preparation for Network. machine # [ 6.603193] systemd-logind[521]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 6.609592] systemd[1]: Starting DHCP Client... machine # [ 6.761400] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0) machine # [ 6.618442] systemd[1]: Starting Address configuration of eth1... machine # [ 6.638824] systemd[1]: Starting Extra networking commands.... machine # [ 6.639861] systemd[1]: Starting User Runtime Directory /run/user/1001... machine # [ 6.660365] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 6.663121] systemd[1]: Finished linger-users.service. machine # [ 6.691351] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 6.692520] nsncd[594]: Oct 19 03:00:21.325 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 6.699204] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 6.703351] systemd[1]: Reached target User and Group Name Lookups. machine # [ 6.704304] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 6.706411] systemd[1]: Stopped Virtual Console Setup. machine # [ 6.708234] systemd[1]: Starting Virtual Console Setup... machine # [ 6.735096] systemd[1]: Finished User Runtime Directory /run/user/1001. machine # [ 6.737439] systemd[1]: Starting User Manager for UID 1001... machine # [ 6.827110] (systemd)[621]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 6.840733] systemd-logind[521]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'. machine # [ 6.887316] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 6.989692] network-addresses-eth1-start[600]: adding address 192.168.1.1/24... done machine # [ 6.991210] network-addresses-eth1-start[600]: adding address 2001:db8:1::1/64... done machine # [ 6.995422] systemd[1]: Finished Address configuration of eth1. machine # [ 7.189976] kvm_amd: TSC scaling supported machine # [ 7.190429] kvm_amd: Nested Virtualization enabled machine # [ 7.193767] kvm_amd: Nested Paging enabled machine # [ 7.194531] kvm_amd: LBR virtualization supported machine # [ 7.195022] kvm_amd: Virtual VMLOAD VMSAVE supported machine # [ 7.195497] kvm_amd: Virtual GIF supported machine # [ 7.199371] kvm_amd: Virtual NMI enabled machine # [ 7.078852] systemd[621]: Queued start job for default target Main User Target. machine # [ 7.086700] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 7.088322] systemd[621]: Created slice User Application Slice. machine # [ 7.089719] systemd[621]: Started Daily Cleanup of User's Temporary Directories. machine # [ 7.090753] systemd[621]: Reached target Paths. machine # [ 7.095249] systemd[621]: Reached target Timers. machine # [ 7.095927] systemd[621]: Starting D-Bus User Message Bus Socket... machine # [ 7.100193] systemd[621]: Starting Create User Files and Directories... machine # [ 7.278394] EDAC MC: Ver: 3.0.0 machine # [ 7.142252] systemd[621]: Finished Create User Files and Directories. machine # [ 7.153961] dhcpcd[629]: dhcpcd-10.3.2 starting machine # [ 7.165505] dhcpcd[693]: dev: loaded udev machine # [ 7.171217] systemd[621]: Listening on D-Bus User Message Bus Socket. machine # [ 7.173524] systemd[621]: Reached target Sockets. machine # [ 7.174851] systemd[621]: Reached target Basic System. machine # [ 7.177424] systemd[1]: Started User Manager for UID 1001. machine # [ 7.178432] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 7.180251] systemd[1]: Finished Extra networking commands.. machine # [ 7.182279] systemd[1]: Reached target Network. machine # [ 7.186183] systemd[1]: Starting Permit User Sessions... machine # [ 7.340557] 8021q: 802.1Q VLAN Support v1.8 machine # [ 7.343197] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 7.227178] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 7.246621] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 7.247733] systemd[621]: Reached target Main User Target. machine # [ 7.249150] systemd[621]: Startup finished in 379ms. machine # [ 7.262381] systemd[1]: Finished Permit User Sessions. machine # [ 7.265700] systemd[1]: Started Getty on tty1. machine # [ 7.267216] systemd[1]: Reached target Login Prompts. machine # [ 7.448524] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 7.460097] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 7.461000] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 7.462862] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 7.463733] cfg80211: failed to load regulatory.db machine # [ 7.491321] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 7.348620] dhcpcd[693]: eth0: waiting for carrier machine # [ 7.349723] dhcpcd[693]: eth0: carrier acquired machine # [ 7.355914] dhcpcd[693]: DUID 00:01:00:01:32:68:44:45:52:54:00:12:34:56 machine # [ 7.356896] dhcpcd[693]: eth0: IAID 00:12:34:56 machine # [ 7.357669] dhcpcd[693]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 7.370085] systemd-vconsole-setup[617]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 7.374276] systemd[1]: Finished Virtual Console Setup. machine # [ 8.235942] dhcpcd[693]: eth0: soliciting a DHCP lease machine # [ 8.397133] NET: Registered PF_PACKET protocol family machine # [ 8.259156] dhcpcd[693]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 8.263151] dhcpcd[693]: eth0: probing address 10.0.2.15/24 machine # [ 9.257195] dhcpcd[693]: eth0: soliciting an IPv6 router machine # [ 9.259364] dhcpcd[693]: eth0: Router Advertisement from fe80::2 machine # [ 9.261401] dhcpcd[693]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 9.263636] dhcpcd[693]: eth0: adding route to fec0::/64 machine # [ 9.265470] dhcpcd[693]: eth0: adding default route via fe80::2 machine # [ 13.442803] dhcpcd[693]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 13.446373] dhcpcd[693]: eth0: adding route to 10.0.2.0/24 machine # [ 13.449241] dhcpcd[693]: eth0: adding default route via 10.0.2.2 machine # [ 13.538704] systemd[1]: Started DHCP Client. machine # [ 13.540428] systemd[1]: Reached target Multi-User System. machine # [ 13.541420] systemd[1]: Startup finished in 872ms (kernel) + 2.944s (initrd) + 9.723s (userspace) = 13.540s. machine: (finished: waiting for unit multi-user.target, in 15.03 seconds) machine: must succeed: /nix/store/3vbcjh6yz1i6lp352svzp92f3jmjppg7-stderr-runner /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test machine # Checking switch inhibitors... done machine # [ 14.220447] nixos[840]: switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test machine # [ 14.222639] systemd[1]: Stopped target Local File Systems. machine # [ 14.224216] systemd[1]: Stopped target Remote File Systems. machine # activating the configuration... machine # setting up /etc... machine # [ 14.444344] systemd[1]: Reload requested from client PID 840 ('.switch-to-conf') (unit backdoor.service)... machine # [ 14.446402] systemd[1]: Reloading... machine # [ 14.666375] systemd[1]: Reloading finished in 217 ms. machine # reloading user units for usertest... machine # [ 14.695947] systemd[621]: Created slice User Core Session Slice. machine # [ 14.698360] systemd[621]: Starting D-Bus User Message Bus... machine # [ 14.719897] 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 # [ 14.722612] 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 # [ 14.725341] 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 # [ 14.728090] 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 # [ 14.730737] dbus-broker-launch[910]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored machine # [ 14.732321] dbus-broker-launch[910]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored machine # [ 14.734315] systemd[621]: Started D-Bus User Message Bus. machine # [ 14.738191] dbus-broker-launch[910]: Ready machine # [ 14.775576] systemd[621]: Reexecution requested from client PID 909 ('switch-to-confi')... machine # [ 14.777961] systemd[621]: Reexecuting. machine # [ 14.797958] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 14.870507] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 14.875601] systemd[621]: Reload requested from client PID 909 ('switch-to-confi')... machine # [ 14.876960] systemd[621]: Reloading... machine # [ 14.948911] systemd[621]: Reloading finished in 70 ms. machine # [ 14.953818] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 14.961066] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 14.962045] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 14.964050] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 14.985157] systemd[621]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 14.993280] systemd[1]: Starting Re-setup tmpfiles on a system that is already running.... machine # [ 15.059174] systemd[1]: Finished Re-setup tmpfiles on a system that is already running.. machine # [ 15.061298] systemd[1]: Reached target Reactivate sysinit units. machine # [ 15.064540] systemd[1]: Reached target Remote File Systems. machine # [ 15.067528] systemd[1]: Starting linger-users.service... machine # [ 15.072725] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 15.074035] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 15.078094] systemd[1]: Reached target Local File Systems. machine # [ 15.085324] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 15.089938] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 15.090844] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 15.092066] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 15.094174] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 15.095676] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 15.096853] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 15.131581] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 15.133383] systemd[1]: Finished linger-users.service. machine # [ 15.240202] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 15.241787] systemd[1]: Finished Create SUID/SGID Wrappers. machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service machine # [ 15.502344] nixos[840]: finished switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3vbcjh6yz1i6lp352svzp92f3jmjppg7-stderr-runner /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test, in 1.51 seconds) machine: must succeed: rm /etc/NIXOS machine: (finished: must succeed: rm /etc/NIXOS, in 0.03 seconds) machine: must succeed: /nix/store/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 # [ 15.754728] nixos[987]: switching to system configuration /nix/store/cssmzw3iavz1v1impl73lvs2bann39bm-nixos-system-other-test machine # [ 15.757025] systemd[1]: Stopped target Remote File Systems. machine # activating the configuration... machine # [ 15.759131] systemd[1]: Stopped target Local File Systems. machine # removing user ‘usertest’ machine # setting up /etc... machine # [ 15.976496] systemd[1]: Reload requested from client PID 987 ('.switch-to-conf') (unit backdoor.service)... machine # [ 15.978181] systemd[1]: Reloading... machine # [ 16.218151] systemd[1]: Reloading finished in 238 ms. machine # reloading user units for usertest... machine # [ 16.284983] systemd[621]: Reexecution requested from client PID 1058 ('switch-to-confi')... machine # [ 16.287723] systemd[621]: Reexecuting. machine # [ 16.309053] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 16.383876] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 16.389032] systemd[621]: Reload requested from client PID 1058 ('switch-to-confi')... machine # [ 16.390251] systemd[621]: Reloading... machine # [ 16.462192] systemd[621]: Reloading finished in 70 ms. machine # [ 16.467970] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 16.476394] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 16.477315] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 16.479939] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 16.502345] systemd[621]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 16.506523] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 16.507618] systemd[1]: Stopping Reactivate sysinit units... machine # [ 16.508588] systemd[1]: Reached target Reactivate sysinit units. machine # reloading the following units: dbus-broker.service machine # [ 16.511848] dbus-broker-launch[502]: Caught SIGHUP, trigger reload. machine # [ 16.513437] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 16.519044] dbus-broker-launch[502]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 16.520784] dbus-broker-launch[502]: NSS returned no entry for 'systemd-timesync' machine # [ 16.521848] dbus-broker-launch[502]: Invalid user-name in /nix/store/2h842v43dq0mx3dmyabal4yr4l01qpk4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 16.530363] systemd[1]: Reloaded D-Bus System Message Bus. machine # restarting the following units: network-addresses-eth1.service machine # [ 16.535139] systemd[1]: Stopping Address configuration of eth1... machine # [ 16.554207] network-addresses-eth1-pre-stop[1070]: deleting address 192.168.1.1/24... done machine # [ 16.559332] network-addresses-eth1-pre-stop[1070]: deleting address 2001:db8:1::1/64... done machine # [ 16.564381] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 16.565892] systemd[1]: Stopped Address configuration of eth1. machine # [ 16.569340] systemd[1]: Starting Address configuration of eth1... machine # [ 16.597690] network-addresses-eth1-start[1075]: adding address 192.168.1.2/24... done machine # [ 16.603059] network-addresses-eth1-start[1075]: adding address 2001:db8:1::2/64... done machine # [ 16.610168] systemd[1]: Finished Address configuration of eth1. machine # [ 16.612545] systemd[1]: Reached target Local File Systems. machine # [ 16.614898] systemd[1]: Reached target Remote File Systems. machine # [ 16.617674] systemd[1]: Starting linger-users.service... machine # [ 16.619474] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 16.621198] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 16.634484] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 16.637899] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 16.639942] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 16.641900] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 16.644687] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 16.646186] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 16.648170] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 16.666882] linger-users-start[1089]: id: ‘usertest’: no such user machine # [ 16.668391] linger-users-start[1086]: Removing linger for missing user usertest machine # [ 16.674906] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 16.677378] systemd[1]: Finished linger-users.service. machine # [ 16.813146] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 16.814736] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 17.069247] 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.53 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 17.236071] nixos[1154]: switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test machine # [ 17.238319] systemd[1]: Stopped target Local File Systems. machine # [ 17.239807] systemd[1]: Stopped target Remote File Systems. machine # [ 17.452199] systemd[1]: Reload requested from client PID 1154 ('.switch-to-conf') (unit backdoor.service)... machine # [ 17.453810] systemd[1]: Reloading... machine # [ 17.684037] systemd[1]: Reloading finished in 228 ms. machine # [ 17.743973] systemd[621]: Reexecution requested from client PID 1223 ('switch-to-confi')... machine # [ 17.746400] systemd[621]: Reexecuting. machine # [ 17.766979] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 17.840369] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 17.845300] systemd[621]: Reload requested from client PID 1223 ('switch-to-confi')... machine # [ 17.847588] systemd[621]: Reloading... machine # [ 17.917627] systemd[621]: Reloading finished in 70 ms. machine # [ 17.923874] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 17.930151] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 17.931037] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 17.933146] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 17.952058] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 17.955531] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 17.956571] systemd[1]: Stopping Reactivate sysinit units... machine # [ 17.957826] systemd[1]: Reached target Reactivate sysinit units. machine # [ 17.961134] dbus-broker-launch[502]: Caught SIGHUP, trigger reload. machine # [ 17.962279] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 17.964916] dbus-broker-launch[502]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 17.966628] dbus-broker-launch[502]: NSS returned no entry for 'systemd-timesync' machine # [ 17.967646] dbus-broker-launch[502]: Invalid user-name in /nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 17.975150] systemd[1]: Reloaded D-Bus System Message Bus. machine # [ 17.979067] systemd[1]: Stopping Address configuration of eth1... machine # [ 17.996721] network-addresses-eth1-pre-stop[1235]: deleting address 192.168.1.2/24... done machine # [ 18.001640] network-addresses-eth1-pre-stop[1235]: deleting address 2001:db8:1::2/64... done machine # [ 18.006379] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 18.008153] systemd[1]: Stopped Address configuration of eth1. machine # [ 18.012439] systemd[1]: Starting Address configuration of eth1... machine # [ 18.039269] network-addresses-eth1-start[1240]: adding address 192.168.1.1/24... done machine # [ 18.044685] network-addresses-eth1-start[1240]: adding address 2001:db8:1::1/64... done machine # [ 18.051651] systemd[1]: Finished Address configuration of eth1. machine # [ 18.056625] systemd[1]: Reached target Remote File Systems. machine # [ 18.060642] systemd[1]: Starting linger-users.service... machine # [ 18.062368] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 18.064101] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 18.070200] systemd[1]: Reached target Local File Systems. machine # [ 18.071181] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 18.076324] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 18.077164] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 18.078315] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 18.080297] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 18.081705] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 18.082877] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 18.113668] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 18.115122] systemd[1]: Finished linger-users.service. machine # [ 18.232191] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 18.234102] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 18.491752] nixos[1154]: finished switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.43 seconds) subtest: user services machine: waiting for unit user@1001.service machine: (finished: waiting for unit user@1001.service, in 0.07 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 18.750294] nixos[1313]: switching to system configuration /nix/store/b1acdf5alvi1qinbv4avbq1ypqs7y4xl-nixos-system-machine-test machine # [ 18.753888] systemd[1]: Stopped target Local File Systems. machine # [ 18.754930] systemd[1]: Stopped target Remote File Systems. machine # [ 18.983327] systemd[1]: Reload requested from client PID 1313 ('.switch-to-conf') (unit backdoor.service)... machine # [ 18.985167] systemd[1]: Reloading... machine # [ 19.221476] systemd[1]: Reloading finished in 235 ms. machine # [ 19.288323] systemd[621]: Reexecution requested from client PID 1382 ('switch-to-confi')... machine # [ 19.291068] systemd[621]: Reexecuting. machine # [ 19.313221] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 19.395488] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 19.400813] systemd[621]: Reload requested from client PID 1382 ('switch-to-confi')... machine # [ 19.402751] systemd[621]: Reloading... machine # [ 19.477274] systemd[621]: Reloading finished in 73 ms. machine # [ 19.483881] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 19.490975] systemd[621]: Starting usertest.service... machine # [ 19.507173] systemd[621]: Finished usertest.service. machine # [ 19.509431] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 19.511131] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 19.512756] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 19.532155] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 19.535470] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 19.536818] systemd[1]: Stopping Reactivate sysinit units... machine # [ 19.538124] systemd[1]: Reached target Reactivate sysinit units. machine # [ 19.541594] systemd[1]: Reached target Remote File Systems. machine # [ 19.544520] systemd[1]: Starting linger-users.service... machine # [ 19.546976] systemd[1]: Reached target Local File Systems. machine # [ 19.550077] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 19.551689] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 19.560555] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 19.565233] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 19.566898] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 19.568087] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 19.570118] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 19.571613] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 19.572856] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 19.608884] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 19.610817] systemd[1]: Finished linger-users.service. machine # [ 19.721157] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 19.723073] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 19.981800] nixos[1313]: finished switching to system configuration /nix/store/b1acdf5alvi1qinbv4avbq1ypqs7y4xl-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.42 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 20.036584] sudo[1452]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 20.049952] sudo[1452]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 20.058046] sudo[1452]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 20.267787] nixos[1461]: switching to system configuration /nix/store/b1acdf5alvi1qinbv4avbq1ypqs7y4xl-nixos-system-machine-test machine # [ 20.270456] systemd[1]: Stopped target Remote File Systems. machine # [ 20.272477] systemd[1]: Stopped target Local File Systems. machine # [ 20.483650] systemd[1]: Reload requested from client PID 1461 ('.switch-to-conf') (unit backdoor.service)... machine # [ 20.485363] systemd[1]: Reloading... machine # [ 20.727225] systemd[1]: Reloading finished in 239 ms. machine # [ 20.792751] systemd[621]: Reexecution requested from client PID 1531 ('switch-to-confi')... machine # [ 20.795513] systemd[621]: Reexecuting. machine # [ 20.817366] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 20.892984] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 20.898532] systemd[621]: Reload requested from client PID 1531 ('switch-to-confi')... machine # [ 20.899984] systemd[621]: Reloading... machine # [ 20.975755] systemd[621]: Reloading finished in 74 ms. machine # [ 20.981972] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 20.990072] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 20.991040] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 20.993257] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 21.014790] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 21.018380] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 21.019615] systemd[1]: Stopping Reactivate sysinit units... machine # [ 21.020564] systemd[1]: Reached target Reactivate sysinit units. machine # [ 21.024132] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 21.026318] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 21.029770] systemd[1]: Reached target Remote File Systems. machine # [ 21.032824] systemd[1]: Starting linger-users.service... machine # [ 21.038110] systemd[1]: Reached target Local File Systems. machine # [ 21.038898] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 21.044237] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 21.045147] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 21.047374] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 21.049434] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 21.050949] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 21.054474] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 21.098329] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 21.100666] systemd[1]: Finished linger-users.service. machine # [ 21.211650] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 21.213692] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 21.470869] nixos[1461]: finished switching to system configuration /nix/store/b1acdf5alvi1qinbv4avbq1ypqs7y4xl-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.42 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1 machine # [ 21.715249] nixos[1602]: switching to system configuration /nix/store/l034yn0wgck1g94n9i8jwya6rkr5sncg-nixos-system-machine-test machine # [ 21.717951] systemd[1]: Stopped target Remote File Systems. machine # [ 21.719895] systemd[1]: Stopped target Local File Systems. machine # [ 21.963667] systemd[1]: Reload requested from client PID 1602 ('.switch-to-conf') (unit backdoor.service)... machine # [ 21.965458] systemd[1]: Reloading... machine # [ 22.265918] systemd[1]: Reloading finished in 298 ms. machine # [ 22.344210] systemd[621]: Stopped usertest.service. machine # [ 22.347106] systemd[621]: Reexecution requested from client PID 1671 ('switch-to-confi')... machine # [ 22.348326] systemd[621]: Reexecuting. machine # [ 22.370560] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 22.450875] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 22.455992] systemd[621]: Reload requested from client PID 1671 ('switch-to-confi')... machine # [ 22.457663] systemd[621]: Reloading... machine # [ 22.529756] systemd[621]: Reloading finished in 71 ms. machine # [ 22.535821] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 22.544283] systemd[621]: Starting usertest.service... machine # [ 22.558171] systemd[621]: Finished usertest.service. machine # [ 22.561063] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 22.561911] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 22.563651] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 22.581280] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 22.584560] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 22.585650] systemd[1]: Stopping Reactivate sysinit units... machine # [ 22.586962] systemd[1]: Reached target Reactivate sysinit units. machine # [ 22.592061] systemd[1]: Reached target Local File Systems. machine # [ 22.594364] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 22.596541] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 22.598329] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 22.599860] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 22.602583] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 22.605398] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.608260] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.610498] systemd[1]: Reached target Remote File Systems. machine # [ 22.613433] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.614796] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 22.746549] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 22.748400] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 22.752574] systemd[1]: Starting linger-users.service... machine # [ 22.779294] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 22.780934] systemd[1]: Finished linger-users.service. machine # [ 23.035630] nixos[1602]: finished switching to system configuration /nix/store/l034yn0wgck1g94n9i8jwya6rkr5sncg-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 1.56 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 23.058795] sudo[1741]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 23.066646] sudo[1741]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 23.074150] sudo[1741]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.04 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 23.234085] nixos[1750]: switching to system configuration /nix/store/gny6bp03gn7p2jrgfcchc1mp8f40i3y6-nixos-system-machine-test machine # [ 23.236528] systemd[1]: Stopped target Remote File Systems. machine # [ 23.237994] systemd[1]: Stopped target Local File Systems. machine # [ 23.492375] systemd[1]: Reload requested from client PID 1750 ('.switch-to-conf') (unit backdoor.service)... machine # [ 23.493989] systemd[1]: Reloading... machine # [ 23.721971] systemd[1]: Reloading finished in 226 ms. machine # [ 23.788906] systemd[621]: Reexecution requested from client PID 1820 ('switch-to-confi')... machine # [ 23.791373] systemd[621]: Reexecuting. machine # [ 23.812159] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.891252] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.896058] systemd[621]: Reload requested from client PID 1820 ('switch-to-confi')... machine # [ 23.897771] systemd[621]: Reloading... machine # [ 23.971682] systemd[621]: Reloading finished in 73 ms. machine # [ 23.976822] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.980464] systemd[621]: Stopped usertest.service. machine # [ 23.982063] systemd[621]: Stopping usertest.service... machine # [ 23.983538] systemd[621]: Starting usertest.service... machine # [ 23.998174] systemd[621]: Finished usertest.service. machine # [ 24.003185] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 24.004236] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 24.006224] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 24.025317] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 24.028541] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 24.030169] systemd[1]: Stopping Reactivate sysinit units... machine # [ 24.030938] systemd[1]: Reached target Reactivate sysinit units. machine # [ 24.032816] systemd[1]: Reached target Remote File Systems. machine # [ 24.034603] systemd[1]: Reached target Local File Systems. machine # [ 24.036569] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.038216] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 24.043909] systemd[1]: Starting linger-users.service... machine # [ 24.053687] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 24.058479] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 24.059538] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 24.061182] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 24.063706] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 24.065631] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.068082] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.101353] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 24.103069] systemd[1]: Finished linger-users.service. machine # [ 24.211099] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 24.212755] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 24.469737] nixos[1750]: finished switching to system configuration /nix/store/gny6bp03gn7p2jrgfcchc1mp8f40i3y6-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1, in 1.40 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 24.517512] sudo[1890]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 24.531944] sudo[1890]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 24.541985] sudo[1890]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.06 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1 machine # [ 24.723162] nixos[1899]: switching to system configuration /nix/store/1vlx1hnlbmnxkhbdq2vycr40slmf75c2-nixos-system-machine-test machine # [ 24.725665] systemd[1]: Stopped target Local File Systems. machine # [ 24.727254] systemd[1]: Stopped target Remote File Systems. machine # [ 24.949582] systemd[1]: Reload requested from client PID 1899 ('.switch-to-conf') (unit backdoor.service)... machine # [ 24.951158] systemd[1]: Reloading... machine # [ 25.180102] systemd[1]: Reloading finished in 226 ms. machine # [ 25.242666] systemd[621]: Reexecution requested from client PID 1968 ('switch-to-confi')... machine # [ 25.245120] systemd[621]: Reexecuting. machine # [ 25.265258] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 25.345243] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 25.349399] systemd[621]: Reload requested from client PID 1968 ('switch-to-confi')... machine # [ 25.351116] systemd[621]: Reloading... machine # [ 25.422887] systemd[621]: Reloading finished in 70 ms. machine # [ 25.428822] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 25.434085] systemd[621]: Reloading usertest.service... machine # [ 25.449785] systemd[621]: Reloaded usertest.service. machine # [ 25.455071] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 25.455935] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 25.457730] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 25.475578] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 25.478533] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 25.479898] systemd[1]: Stopping Reactivate sysinit units... machine # [ 25.481161] systemd[1]: Reached target Reactivate sysinit units. machine # [ 25.483535] systemd[1]: Reached target Remote File Systems. machine # [ 25.485877] systemd[1]: Reached target Local File Systems. machine # [ 25.491139] systemd[1]: Starting linger-users.service... machine # [ 25.496747] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 25.501905] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 25.502855] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 25.504039] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 25.506831] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 25.509527] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 25.510671] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 25.514134] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 25.515329] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 25.551286] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 25.552474] systemd[1]: Finished linger-users.service. machine # [ 25.662702] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 25.664474] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 25.922404] nixos[1899]: finished switching to system configuration /nix/store/1vlx1hnlbmnxkhbdq2vycr40slmf75c2-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1, in 1.39 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 25.971412] sudo[2038]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 25.985779] sudo[2038]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 25.993402] sudo[2038]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.06 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 26.136562] nixos[2047]: switching to system configuration /nix/store/b1acdf5alvi1qinbv4avbq1ypqs7y4xl-nixos-system-machine-test machine # [ 26.138671] systemd[1]: Stopped target Local File Systems. machine # [ 26.140264] systemd[1]: Stopped target Remote File Systems. machine # [ 26.328520] systemd[1]: Reload requested from client PID 2047 ('.switch-to-conf') (unit backdoor.service)... machine # [ 26.330160] systemd[1]: Reloading... machine # [ 26.541988] systemd[1]: Reloading finished in 210 ms. machine # [ 26.619277] systemd[621]: Stopped usertest.service. machine # [ 26.621244] systemd[621]: Reexecution requested from client PID 2116 ('switch-to-confi')... machine # [ 26.622802] systemd[621]: Reexecuting. machine # [ 26.642259] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 26.716470] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 26.721937] systemd[621]: Reload requested from client PID 2116 ('switch-to-confi')... machine # [ 26.723454] systemd[621]: Reloading... machine # [ 26.791181] systemd[621]: Reloading finished in 66 ms. machine # [ 26.796869] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 26.803750] systemd[621]: Starting usertest.service... machine # [ 26.822301] systemd[621]: Finished usertest.service. machine # [ 26.824349] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 26.826545] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 26.829065] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 26.847979] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 26.850832] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 26.852208] systemd[1]: Stopping Reactivate sysinit units... machine # [ 26.852945] systemd[1]: Reached target Reactivate sysinit units. machine # [ 26.857571] systemd[1]: Reached target Remote File Systems. machine # [ 26.859062] systemd[1]: Reached target Local File Systems. machine # [ 26.861710] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.863650] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 26.867275] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 26.870763] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 26.872404] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 26.874323] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 26.876304] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 26.877769] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.881135] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.007540] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 27.009627] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 27.013335] systemd[1]: Starting linger-users.service... machine # [ 27.038248] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 27.039742] systemd[1]: Finished linger-users.service. machine # [ 27.296963] nixos[2047]: finished switching to system configuration /nix/store/b1acdf5alvi1qinbv4avbq1ypqs7y4xl-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.31 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 27.331826] sudo[2187]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 27.338885] sudo[2187]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 27.345942] sudo[2187]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.04 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1 machine # [ 27.525230] nixos[2196]: switching to system configuration /nix/store/4zpkjzx3gmx0w3jbivmwlmdifqqvwkql-nixos-system-machine-test machine # [ 27.527933] systemd[1]: Stopped target Remote File Systems. machine # [ 27.529641] systemd[1]: Stopped target Local File Systems. machine # [ 27.775061] systemd[1]: Reload requested from client PID 2196 ('.switch-to-conf') (unit backdoor.service)... machine # [ 27.776712] systemd[1]: Reloading... machine # [ 28.007419] systemd[1]: Reloading finished in 229 ms. machine # [ 28.077195] systemd[621]: Reexecution requested from client PID 2265 ('switch-to-confi')... machine # [ 28.078543] systemd[621]: Reexecuting. machine # [ 28.095088] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 28.169454] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 28.174584] systemd[621]: Reload requested from client PID 2265 ('switch-to-confi')... machine # [ 28.176984] systemd[621]: Reloading... machine # [ 28.250794] systemd[621]: Reloading finished in 74 ms. machine # [ 28.255823] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 28.261466] systemd[621]: Reloading usertest.service... machine # [ 28.275786] systemd[621]: Reloaded usertest.service. machine # [ 28.280405] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 28.282065] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 28.283610] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 28.302352] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 28.305683] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 28.306658] systemd[1]: Stopping Reactivate sysinit units... machine # [ 28.308033] systemd[1]: Reached target Reactivate sysinit units. machine # [ 28.309290] systemd[1]: Reached target Remote File Systems. machine # [ 28.311783] systemd[1]: Reached target Local File Systems. machine # [ 28.316792] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 28.320803] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 28.322069] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 28.323232] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 28.325184] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 28.326744] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 28.332362] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 28.333762] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 28.335047] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 28.462030] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 28.463638] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 28.467711] systemd[1]: Starting linger-users.service... machine # [ 28.493437] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 28.494944] systemd[1]: Finished linger-users.service. machine # [ 28.752597] nixos[2196]: finished switching to system configuration /nix/store/4zpkjzx3gmx0w3jbivmwlmdifqqvwkql-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 1.41 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 28.798673] sudo[2335]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 28.813543] sudo[2335]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 28.823903] sudo[2335]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds) machine: must fail: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1 machine # [ 29.005656] nixos[2344]: switching to system configuration /nix/store/pck2mjyaxl416rrddxx4p96h8lyzkj6n-nixos-system-machine-test machine # [ 29.008946] systemd[1]: Stopped target Remote File Systems. machine # [ 29.010081] systemd[1]: Stopped target Local File Systems. machine # [ 29.230737] systemd[1]: Reload requested from client PID 2344 ('.switch-to-conf') (unit backdoor.service)... machine # [ 29.232459] systemd[1]: Reloading... machine # [ 29.457939] systemd[1]: Reloading finished in 223 ms. machine # [ 29.526922] systemd[621]: Stopped usertest.service. machine # [ 29.528191] systemd[621]: Reexecution requested from client PID 2413 ('switch-to-confi')... machine # [ 29.530722] systemd[621]: Reexecuting. machine # [ 29.551096] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.624694] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.629686] systemd[621]: Reload requested from client PID 2413 ('switch-to-confi')... machine # [ 29.630937] systemd[621]: Reloading... machine # [ 29.703813] systemd[621]: Reloading finished in 71 ms. machine # [ 29.707816] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.714281] systemd[621]: Starting usertest.service... machine # [ 29.739254] systemd[621]: usertest.service: Main process exited, code=exited, status=1/FAILURE machine # [ 29.740665] systemd[621]: usertest.service: Failed with result 'exit-code'. machine # [ 29.743373] systemd[621]: Failed to start usertest.service. machine # [ 29.748070] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 29.748964] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 29.751936] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 29.786945] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 29.791705] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 29.792980] systemd[1]: Stopping Reactivate sysinit units... machine # [ 29.794310] systemd[1]: Reached target Reactivate sysinit units. machine # [ 29.798954] systemd[1]: Reached target Local File Systems. machine # [ 29.801187] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 29.804207] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 29.805024] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 29.809088] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 29.811865] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 29.813279] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 29.816716] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 29.818146] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 29.819357] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 29.825138] systemd[1]: Reached target Remote File Systems. machine # [ 29.953874] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 29.955713] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 29.959859] systemd[1]: Starting linger-users.service... machine # [ 29.986410] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 29.987905] systemd[1]: Finished linger-users.service. machine # [ 30.245554] nixos[2344]: switching to system configuration /nix/store/pck2mjyaxl416rrddxx4p96h8lyzkj6n-nixos-system-machine-test failed (status 4) machine: (finished: must fail: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 1.43 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 30.456519] nixos[2487]: switching to system configuration /nix/store/b1acdf5alvi1qinbv4avbq1ypqs7y4xl-nixos-system-machine-test machine # [ 30.459231] systemd[1]: Stopped target Local File Systems. machine # [ 30.461110] systemd[1]: Stopped target Remote File Systems. machine # [ 30.669654] systemd[1]: Reload requested from client PID 2487 ('.switch-to-conf') (unit backdoor.service)... machine # [ 30.671366] systemd[1]: Reloading... machine # [ 30.907556] systemd[1]: Reloading finished in 234 ms. machine # [ 30.970566] systemd[621]: Reexecution requested from client PID 2556 ('switch-to-confi')... machine # [ 30.972293] systemd[621]: Reexecuting. machine # [ 30.992960] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 31.065649] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 31.071037] systemd[621]: Reload requested from client PID 2556 ('switch-to-confi')... machine # [ 31.072256] systemd[621]: Reloading... machine # [ 31.144457] systemd[621]: Reloading finished in 70 ms. machine # [ 31.149814] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 31.156032] systemd[621]: Starting usertest.service... machine # [ 31.170930] systemd[621]: Finished usertest.service. machine # [ 31.173972] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 31.174865] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 31.176230] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 31.194456] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 31.197444] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 31.198762] systemd[1]: Stopping Reactivate sysinit units... machine # [ 31.199760] systemd[1]: Reached target Reactivate sysinit units. machine # [ 31.202499] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 31.204265] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 31.207107] systemd[1]: Reached target Remote File Systems. machine # [ 31.209245] systemd[1]: Starting linger-users.service... machine # [ 31.211744] systemd[1]: Reached target Local File Systems. machine # [ 31.219849] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 31.224078] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 31.224959] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 31.226162] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 31.228219] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 31.229690] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 31.234847] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 31.265632] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 31.267350] systemd[1]: Finished linger-users.service. machine # [ 31.376846] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 31.378544] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 31.635673] nixos[2487]: finished switching to system configuration /nix/store/b1acdf5alvi1qinbv4avbq1ypqs7y4xl-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.39 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 31.683265] sudo[2626]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 31.697546] sudo[2626]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 31.708567] sudo[2626]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 31.888202] nixos[2635]: switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test machine # [ 31.891041] systemd[1]: Stopped target Local File Systems. machine # [ 31.892201] systemd[1]: Stopped target Remote File Systems. machine # [ 32.095132] systemd[1]: Reload requested from client PID 2635 ('.switch-to-conf') (unit backdoor.service)... machine # [ 32.096881] systemd[1]: Reloading... machine # [ 32.327095] systemd[1]: Reloading finished in 228 ms. machine # [ 32.388511] systemd[621]: Stopped usertest.service. machine # [ 32.389813] systemd[621]: Reexecution requested from client PID 2704 ('switch-to-confi')... machine # [ 32.392240] systemd[621]: Reexecuting. machine # [ 32.413066] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.487352] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.492535] systemd[621]: Reload requested from client PID 2704 ('switch-to-confi')... machine # [ 32.493791] systemd[621]: Reloading... machine # [ 32.564667] systemd[621]: Reloading finished in 69 ms. machine # [ 32.568817] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.574972] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 32.576402] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 32.578831] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 32.598538] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 32.601554] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 32.602793] systemd[1]: Stopping Reactivate sysinit units... machine # [ 32.603785] systemd[1]: Reached target Reactivate sysinit units. machine # [ 32.608562] systemd[1]: Reached target Remote File Systems. machine # [ 32.612980] systemd[1]: Reached target Local File Systems. machine # [ 32.615071] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 32.617285] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 32.619119] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 32.620758] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 32.622775] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 32.624198] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 32.625439] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 32.629991] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 32.631588] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 32.761988] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 32.764081] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 32.767690] systemd[1]: Starting linger-users.service... machine # [ 32.794865] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 32.796371] systemd[1]: Finished linger-users.service. machine # [ 33.052790] nixos[2635]: finished switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.35 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 33.090327] sudo[2773]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 33.097594] sudo[2773]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 33.105400] sudo[2773]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.05 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 33.123971] sudo[2779]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 33.130846] sudo[2779]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 33.136420] sudo[2779]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.03 seconds) machine: must succeed: sudo -u usertest cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 33.154270] sudo[2785]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 33.161373] sudo[2785]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 33.167209] sudo[2785]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 33.185406] sudo[2791]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 33.192463] sudo[2791]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 33.197526] sudo[2791]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 33.215324] sudo[2797]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload machine # [ 33.223417] sudo[2797]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 33.228688] systemd[621]: Reload requested from client PID 2802 ('systemctl')... machine # [ 33.229841] systemd[621]: Reloading... machine # [ 33.302798] systemd[621]: Reloading finished in 71 ms. machine # [ 33.307813] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.315799] sudo[2797]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 33.336277] sudo[2807]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service machine # [ 33.343527] sudo[2807]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 33.352983] systemd[621]: Starting migrated.service... machine # [ 33.371024] systemd[621]: Finished migrated.service. machine # [ 33.374068] sudo[2807]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 33.395750] sudo[2815]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 33.403312] sudo[2815]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 33.410213] sudo[2815]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 33.429924] sudo[2821]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 33.437494] sudo[2821]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 33.443165] sudo[2821]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 33.462704] sudo[2827]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 33.470421] sudo[2827]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 33.481797] sudo[2827]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1 machine # [ 33.657811] nixos[2836]: switching to system configuration /nix/store/1278my32hhny82vdmqzcv00g2qfqrsh2-nixos-system-machine-test machine # [ 33.661250] systemd[1]: Stopped target Remote File Systems. machine # [ 33.662258] systemd[1]: Stopped target Local File Systems. machine # [ 33.876092] systemd[1]: Reload requested from client PID 2836 ('.switch-to-conf') (unit backdoor.service)... machine # [ 33.877759] systemd[1]: Reloading... machine # [ 34.106887] systemd[1]: Reloading finished in 227 ms. machine # [ 34.175264] systemd[621]: Reexecution requested from client PID 2905 ('switch-to-confi')... machine # [ 34.176556] systemd[621]: Reexecuting. machine # [ 34.196976] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.270886] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.275975] systemd[621]: Reload requested from client PID 2905 ('switch-to-confi')... machine # [ 34.277795] systemd[621]: Reloading... machine # [ 34.349493] systemd[621]: Reloading finished in 69 ms. machine # [ 34.353874] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.361115] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 34.361978] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 34.364107] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 34.400307] systemd[621]: Reload requested from client PID 2917 ('systemctl') (unit nixos-activation.service)... machine # [ 34.402037] systemd[621]: Reloading... machine # [ 34.478697] systemd[621]: Reloading finished in 75 ms. machine # [ 34.483987] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.498304] systemd[621]: Stopped migrated.service. machine # [ 34.505308] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 34.507482] systemd[621]: Reload requested from client PID 2905 ('switch-to-confi')... machine # [ 34.508896] systemd[621]: Reloading... machine # [ 34.580269] systemd[621]: Reloading finished in 70 ms. machine # [ 34.585829] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.593691] systemd[621]: Starting migrated.service... machine # [ 34.609692] systemd[621]: Finished migrated.service. machine # [ 34.612585] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 34.613892] systemd[1]: Stopping Reactivate sysinit units... machine # [ 34.615516] systemd[1]: Reached target Reactivate sysinit units. machine # [ 34.618799] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.620494] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 34.623687] systemd[1]: Reached target Remote File Systems. machine # [ 34.626579] systemd[1]: Starting linger-users.service... machine # [ 34.631287] systemd[1]: Reached target Local File Systems. machine # [ 34.632113] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 34.636711] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 34.637512] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 34.638663] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 34.640683] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 34.642096] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.643289] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.684670] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 34.686507] systemd[1]: Finished linger-users.service. machine # [ 34.785780] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 34.787746] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 35.046410] nixos[2836]: finished switching to system configuration /nix/store/1278my32hhny82vdmqzcv00g2qfqrsh2-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 1.57 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 35.073629] sudo[2987]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 35.081543] sudo[2987]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 35.088370] sudo[2987]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 35.107975] sudo[2993]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 35.115517] sudo[2993]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 35.127128] sudo[2993]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 35.147178] sudo[2999]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 35.154408] sudo[2999]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 35.159781] sudo[2999]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 35.336811] nixos[3008]: switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test machine # [ 35.339336] systemd[1]: Stopped target Remote File Systems. machine # [ 35.340985] systemd[1]: Stopped target Local File Systems. machine # [ 35.548546] systemd[1]: Reload requested from client PID 3008 ('.switch-to-conf') (unit backdoor.service)... machine # [ 35.550165] systemd[1]: Reloading... machine # [ 35.781573] systemd[1]: Reloading finished in 229 ms. machine # [ 35.848563] systemd[621]: Stopped migrated.service. machine # [ 35.849709] systemd[621]: Reexecution requested from client PID 3077 ('switch-to-confi')... machine # [ 35.852182] systemd[621]: Reexecuting. machine # [ 35.873069] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.947676] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.953226] systemd[621]: Reload requested from client PID 3077 ('switch-to-confi')... machine # [ 35.954590] systemd[621]: Reloading... machine # [ 36.024755] systemd[621]: Reloading finished in 69 ms. machine # [ 36.030856] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.037297] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 36.039089] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 36.040908] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 36.060495] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 36.063714] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 36.064743] systemd[1]: Stopping Reactivate sysinit units... machine # [ 36.065895] systemd[1]: Reached target Reactivate sysinit units. machine # [ 36.070930] systemd[1]: Reached target Local File Systems. machine # [ 36.073338] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 36.075453] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 36.077335] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 36.079426] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 36.081864] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 36.085329] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.086546] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.090306] systemd[1]: Reached target Remote File Systems. machine # [ 36.093200] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.094419] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 36.258969] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 36.261252] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 36.265603] systemd[1]: Starting linger-users.service... machine # [ 36.297480] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 36.299244] systemd[1]: Finished linger-users.service. machine # [ 36.556900] nixos[3008]: finished switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.40 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 36.603463] sudo[3146]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 36.618462] sudo[3146]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 36.626140] sudo[3146]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 36.646972] sudo[3152]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 36.654709] sudo[3152]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 36.660632] sudo[3152]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.03 seconds) machine: must succeed: sudo -u usertest cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 36.682308] sudo[3158]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 36.690514] sudo[3158]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 36.696251] sudo[3158]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.04 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 36.716205] sudo[3164]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 36.723668] sudo[3164]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 36.729201] sudo[3164]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 36.748605] sudo[3170]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload machine # [ 36.756355] sudo[3170]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 36.761654] systemd[621]: Reload requested from client PID 3175 ('systemctl')... machine # [ 36.762792] systemd[621]: Reloading... machine # [ 36.835950] systemd[621]: Reloading finished in 71 ms. machine # [ 36.841829] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.849272] sudo[3170]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 36.869599] sudo[3180]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service machine # [ 36.877309] sudo[3180]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 36.886262] systemd[621]: Starting migrated.service... machine # [ 36.902361] systemd[621]: Finished migrated.service. machine # [ 36.905113] sudo[3180]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 36.925321] sudo[3188]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 36.932807] sudo[3188]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 36.939785] sudo[3188]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 36.959722] sudo[3194]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 36.966946] sudo[3194]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 36.972478] sudo[3194]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 36.991841] sudo[3200]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 36.999248] sudo[3200]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 37.010118] sudo[3200]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1 machine # [ 37.189097] nixos[3209]: switching to system configuration /nix/store/w391wzbi5fafv0qjb12ajpl26p7ygd50-nixos-system-machine-test machine # [ 37.191459] systemd[1]: Stopped target Local File Systems. machine # [ 37.193453] systemd[1]: Stopped target Remote File Systems. machine # [ 37.415987] systemd[1]: Reload requested from client PID 3209 ('.switch-to-conf') (unit backdoor.service)... machine # [ 37.417716] systemd[1]: Reloading... machine # [ 37.646072] systemd[1]: Reloading finished in 226 ms. machine # [ 37.710080] systemd[621]: Reexecution requested from client PID 3278 ('switch-to-confi')... machine # [ 37.712655] systemd[621]: Reexecuting. machine # [ 37.733113] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.807533] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.812770] systemd[621]: Reload requested from client PID 3278 ('switch-to-confi')... machine # [ 37.814579] systemd[621]: Reloading... machine # [ 37.885751] systemd[621]: Reloading finished in 70 ms. machine # [ 37.891113] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.898167] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 37.899060] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 37.901304] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 37.926128] systemd[621]: Reload requested from client PID 3290 ('systemctl') (unit nixos-activation.service)... machine # [ 37.927797] systemd[621]: Reloading... machine # [ 38.003476] systemd[621]: Reloading finished in 74 ms. machine # [ 38.009065] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 38.020105] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 38.022368] systemd[621]: Reload requested from client PID 3278 ('switch-to-confi')... machine # [ 38.023842] systemd[621]: Reloading... machine # [ 38.094768] systemd[621]: Reloading finished in 69 ms. machine # [ 38.100919] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 38.107127] systemd[621]: Stopped migrated.service. machine # [ 38.107899] systemd[621]: Stopping migrated.service... machine # [ 38.109988] systemd[621]: Starting migrated.service... machine # [ 38.126545] systemd[621]: Finished migrated.service. machine # [ 38.132107] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 38.133706] systemd[1]: Stopping Reactivate sysinit units... machine # [ 38.134602] systemd[1]: Reached target Reactivate sysinit units. machine # [ 38.137917] systemd[1]: Reached target Local File Systems. machine # [ 38.139941] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 38.142916] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 38.143773] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 38.144930] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 38.146873] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 38.148387] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 38.152459] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 38.153964] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 38.155521] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 38.159100] systemd[1]: Reached target Remote File Systems. machine # [ 38.290300] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 38.291876] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 38.295881] systemd[1]: Starting linger-users.service... machine # [ 38.324755] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 38.326297] systemd[1]: Finished linger-users.service. machine # [ 38.580747] nixos[3209]: finished switching to system configuration /nix/store/w391wzbi5fafv0qjb12ajpl26p7ygd50-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 1.57 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 38.605921] sudo[3359]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 38.613771] sudo[3359]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 38.621139] sudo[3359]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 38.640948] sudo[3365]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 38.648743] sudo[3365]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 38.660850] sudo[3365]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 38.681668] sudo[3371]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 38.689310] sudo[3371]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 38.695264] sudo[3371]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 38.875755] nixos[3380]: switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test machine # [ 38.877945] systemd[1]: Stopped target Local File Systems. machine # [ 38.879694] systemd[1]: Stopped target Remote File Systems. machine # [ 39.083774] systemd[1]: Reload requested from client PID 3380 ('.switch-to-conf') (unit backdoor.service)... machine # [ 39.085433] systemd[1]: Reloading... machine # [ 39.316607] systemd[1]: Reloading finished in 229 ms. machine # [ 39.414474] systemd[621]: Stopped migrated.service. machine # [ 39.415870] systemd[621]: Reexecution requested from client PID 3449 ('switch-to-confi')... machine # [ 39.418257] systemd[621]: Reexecuting. machine # [ 39.439072] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.512716] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.517700] systemd[621]: Reload requested from client PID 3449 ('switch-to-confi')... machine # [ 39.518892] systemd[621]: Reloading... machine # [ 39.593623] systemd[621]: Reloading finished in 73 ms. machine # [ 39.598824] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.604694] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 39.606128] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 39.608186] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 39.627644] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 39.631079] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 39.631919] systemd[1]: Stopping Reactivate sysinit units... machine # [ 39.632959] systemd[1]: Reached target Reactivate sysinit units. machine # [ 39.635609] systemd[1]: Reached target Remote File Systems. machine # [ 39.638214] systemd[1]: Starting linger-users.service... machine # [ 39.645075] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.646292] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 39.651778] systemd[1]: Reached target Local File Systems. machine # [ 39.653297] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 39.657359] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 39.659293] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 39.660480] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 39.662509] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 39.664044] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.665248] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.701946] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 39.703154] systemd[1]: Finished linger-users.service. machine # [ 39.821758] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 39.824655] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 40.081443] nixos[3380]: finished switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.39 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 40.128993] sudo[3518]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 40.143434] sudo[3518]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 40.152059] sudo[3518]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 40.174317] sudo[3524]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 40.183265] sudo[3524]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 40.189125] sudo[3524]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.04 seconds) machine: must succeed: sudo -u usertest cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 40.210187] sudo[3530]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 40.218431] sudo[3530]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 40.224878] sudo[3530]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.04 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 40.246366] sudo[3536]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 40.254350] sudo[3536]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 40.260123] sudo[3536]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 40.280191] sudo[3542]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload machine # [ 40.287780] sudo[3542]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 40.293640] systemd[621]: Reload requested from client PID 3547 ('systemctl')... machine # [ 40.295539] systemd[621]: Reloading... machine # [ 40.372439] systemd[621]: Reloading finished in 75 ms. machine # [ 40.377899] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 40.385530] sudo[3542]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.13 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 40.407337] sudo[3552]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service machine # [ 40.414796] sudo[3552]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 40.424273] systemd[621]: Starting migrated.service... machine # [ 40.439297] systemd[621]: Finished migrated.service. machine # [ 40.442154] sudo[3552]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 40.463830] sudo[3560]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 40.471563] sudo[3560]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 40.478622] sudo[3560]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 40.498598] sudo[3566]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 40.506487] sudo[3566]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 40.511852] sudo[3566]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 40.536244] sudo[3572]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 40.545983] sudo[3572]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 40.557794] sudo[3572]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1 machine # [ 40.741358] nixos[3581]: switching to system configuration /nix/store/g8pkh9x0w8hfl3ylrfzm8nilylf98gmw-nixos-system-machine-test machine # [ 40.743782] systemd[1]: Stopped target Remote File Systems. machine # [ 40.745693] systemd[1]: Stopped target Local File Systems. machine # [ 40.969974] systemd[1]: Reload requested from client PID 3581 ('.switch-to-conf') (unit backdoor.service)... machine # [ 40.971601] systemd[1]: Reloading... machine # [ 41.202086] systemd[1]: Reloading finished in 229 ms. machine # [ 41.268086] systemd[621]: Reexecution requested from client PID 3650 ('switch-to-confi')... machine # [ 41.270409] systemd[621]: Reexecuting. machine # [ 41.291149] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 41.365364] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 41.370768] systemd[621]: Reload requested from client PID 3650 ('switch-to-confi')... machine # [ 41.372105] systemd[621]: Reloading... machine # [ 41.437887] systemd[621]: Reloading finished in 64 ms. machine # [ 41.442820] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 41.448743] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 41.449757] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 41.452311] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 41.475698] systemd[621]: Reload requested from client PID 3662 ('systemctl') (unit nixos-activation.service)... machine # [ 41.477743] systemd[621]: Reloading... machine # [ 41.552197] systemd[621]: Reloading finished in 73 ms. machine # [ 41.558022] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 41.568317] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 41.570564] systemd[621]: Reload requested from client PID 3650 ('switch-to-confi')... machine # [ 41.571977] systemd[621]: Reloading... machine # [ 41.643895] systemd[621]: Reloading finished in 70 ms. machine # [ 41.649870] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 41.656891] systemd[621]: Reloading migrated.service... machine # [ 41.673174] systemd[621]: Reloaded migrated.service. machine # [ 41.678671] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 41.679896] systemd[1]: Stopping Reactivate sysinit units... machine # [ 41.680958] systemd[1]: Reached target Reactivate sysinit units. machine # [ 41.687298] systemd[1]: Reached target Local File Systems. machine # [ 41.689172] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 41.692202] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 41.694060] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 41.695269] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 41.697290] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 41.698795] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 41.699986] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 41.703556] systemd[1]: Reached target Remote File Systems. machine # [ 41.706523] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 41.708906] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 41.836325] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 41.839128] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 41.842501] systemd[1]: Starting linger-users.service... machine # [ 41.869454] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 41.870961] systemd[1]: Finished linger-users.service. machine # [ 42.127700] nixos[3581]: finished switching to system configuration /nix/store/g8pkh9x0w8hfl3ylrfzm8nilylf98gmw-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 1.58 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 42.177773] sudo[3731]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 42.192521] sudo[3731]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 42.200214] sudo[3731]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 42.220937] sudo[3737]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 42.228384] sudo[3737]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 42.235570] sudo[3737]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 42.425647] nixos[3746]: switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test machine # [ 42.427800] systemd[1]: Stopped target Remote File Systems. machine # [ 42.429648] systemd[1]: Stopped target Local File Systems. machine # [ 42.641971] systemd[1]: Reload requested from client PID 3746 ('.switch-to-conf') (unit backdoor.service)... machine # [ 42.643619] systemd[1]: Reloading... machine # [ 42.876217] systemd[1]: Reloading finished in 230 ms. machine # [ 42.938904] systemd[621]: Stopped migrated.service. machine # [ 42.940333] systemd[621]: Reexecution requested from client PID 3815 ('switch-to-confi')... machine # [ 42.942800] systemd[621]: Reexecuting. machine # [ 42.964205] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.036496] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.042552] systemd[621]: Reload requested from client PID 3815 ('switch-to-confi')... machine # [ 43.043850] systemd[621]: Reloading... machine # [ 43.114628] systemd[621]: Reloading finished in 69 ms. machine # [ 43.119821] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.126958] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 43.127875] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 43.129593] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 43.151830] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 43.154983] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 43.156577] systemd[1]: Stopping Reactivate sysinit units... machine # [ 43.157519] systemd[1]: Reached target Reactivate sysinit units. machine # [ 43.160876] systemd[1]: Reached target Local File Systems. machine # [ 43.163353] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 43.165571] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 43.167417] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 43.168635] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 43.170726] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 43.174392] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.176267] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.178571] systemd[1]: Reached target Remote File Systems. machine # [ 43.180941] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.182181] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 43.314767] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 43.316626] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 43.320566] systemd[1]: Starting linger-users.service... machine # [ 43.346633] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 43.348108] systemd[1]: Finished linger-users.service. machine # [ 43.605735] nixos[3746]: finished switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.38 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 43.652649] sudo[3883]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 43.666787] sudo[3883]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 43.678058] sudo[3883]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 43.698304] sudo[3889]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 43.705624] sudo[3889]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 43.711369] sudo[3889]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.03 seconds) machine: must succeed: sudo -u usertest cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 43.731345] sudo[3895]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 43.738978] sudo[3895]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 43.744710] sudo[3895]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 43.764312] sudo[3901]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 43.771974] sudo[3901]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 43.777634] sudo[3901]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 43.801319] sudo[3907]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload machine # [ 43.809471] sudo[3907]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 43.815112] systemd[621]: Reload requested from client PID 3912 ('systemctl')... machine # [ 43.816304] systemd[621]: Reloading... machine # [ 43.893259] systemd[621]: Reloading finished in 74 ms. machine # [ 43.897828] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.904772] sudo[3907]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.13 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 43.926957] sudo[3917]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service machine # [ 43.934564] sudo[3917]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 43.943918] systemd[621]: Starting migrated.service... machine # [ 43.959292] systemd[621]: Finished migrated.service. machine # [ 43.962146] sudo[3917]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 43.982456] sudo[3925]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 43.990301] sudo[3925]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 43.996650] sudo[3925]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 44.016574] sudo[3931]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 44.023967] sudo[3931]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 44.029688] sudo[3931]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 44.049258] sudo[3937]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 44.056701] sudo[3937]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 44.067520] sudo[3937]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1 machine # [ 44.257061] nixos[3946]: switching to system configuration /nix/store/0b8x0kinzbhfn9b0zk2hsyzw4q0bnh60-nixos-system-machine-test machine # [ 44.259446] systemd[1]: Stopped target Local File Systems. machine # [ 44.261240] systemd[1]: Stopped target Remote File Systems. machine # [ 44.484235] systemd[1]: Reload requested from client PID 3946 ('.switch-to-conf') (unit backdoor.service)... machine # [ 44.485869] systemd[1]: Reloading... machine # [ 44.717938] systemd[1]: Reloading finished in 230 ms. machine # [ 44.786508] systemd[621]: Reexecution requested from client PID 4015 ('switch-to-confi')... machine # [ 44.789123] systemd[621]: Reexecuting. machine # [ 44.810054] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 44.884359] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 44.889528] systemd[621]: Reload requested from client PID 4015 ('switch-to-confi')... machine # [ 44.890892] systemd[621]: Reloading... machine # [ 44.961513] systemd[621]: Reloading finished in 69 ms. machine # [ 44.966806] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 44.973441] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 44.974339] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 44.976477] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 44.999497] systemd[621]: Reload requested from client PID 4027 ('systemctl') (unit nixos-activation.service)... machine # [ 45.001279] systemd[621]: Reloading... machine # [ 45.074379] systemd[621]: Reloading finished in 71 ms. machine # [ 45.079990] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.090204] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 45.092368] systemd[621]: Reload requested from client PID 4015 ('switch-to-confi')... machine # [ 45.093664] systemd[621]: Reloading... machine # [ 45.165335] systemd[621]: Reloading finished in 70 ms. machine # [ 45.170847] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.179719] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 45.180897] systemd[1]: Stopping Reactivate sysinit units... machine # [ 45.181941] systemd[1]: Reached target Reactivate sysinit units. machine # [ 45.184033] systemd[1]: Reached target Local File Systems. machine # [ 45.186114] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 45.188464] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 45.190362] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 45.192279] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 45.194251] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 45.195733] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 45.196940] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 45.201439] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 45.202620] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 45.208485] systemd[1]: Reached target Remote File Systems. machine # [ 45.335151] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 45.336928] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 45.340530] systemd[1]: Starting linger-users.service... machine # [ 45.368198] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 45.369726] systemd[1]: Finished linger-users.service. machine # [ 45.627436] nixos[3946]: finished switching to system configuration /nix/store/0b8x0kinzbhfn9b0zk2hsyzw4q0bnh60-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 1.57 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 45.672821] sudo[4094]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 45.688763] sudo[4094]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 45.699754] sudo[4094]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 45.720601] sudo[4100]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 45.727828] sudo[4100]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 45.733491] sudo[4100]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 45.915662] nixos[4109]: switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test machine # [ 45.917851] systemd[1]: Stopped target Remote File Systems. machine # [ 45.919523] systemd[1]: Stopped target Local File Systems. machine # [ 46.126505] systemd[1]: Reload requested from client PID 4109 ('.switch-to-conf') (unit backdoor.service)... machine # [ 46.128153] systemd[1]: Reloading... machine # [ 46.366317] systemd[1]: Reloading finished in 235 ms. machine # [ 46.430555] systemd[621]: Stopped migrated.service. machine # [ 46.431982] systemd[621]: Reexecution requested from client PID 4178 ('switch-to-confi')... machine # [ 46.433936] systemd[621]: Reexecuting. machine # [ 46.454980] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.528341] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.533404] systemd[621]: Reload requested from client PID 4178 ('switch-to-confi')... machine # [ 46.535695] systemd[621]: Reloading... machine # [ 46.604358] systemd[621]: Reloading finished in 69 ms. machine # [ 46.609097] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.616300] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 46.618117] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 46.619674] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 46.640135] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 46.643060] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 46.644630] systemd[1]: Stopping Reactivate sysinit units... machine # [ 46.645558] systemd[1]: Reached target Reactivate sysinit units. machine # [ 46.648057] systemd[1]: Reached target Remote File Systems. machine # [ 46.650532] systemd[1]: Starting linger-users.service... machine # [ 46.652182] systemd[1]: Reached target Local File Systems. machine # [ 46.657415] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.659050] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 46.665255] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 46.668546] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 46.670304] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 46.672145] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 46.674155] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 46.675679] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.676909] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.713089] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 46.715554] systemd[1]: Finished linger-users.service. machine # [ 46.820220] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 46.822123] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 47.080372] nixos[4109]: finished switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.35 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 47.126577] sudo[4246]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 47.141585] sudo[4246]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.150085] sudo[4246]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user machine # [ 47.170729] sudo[4252]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user machine # [ 47.177949] sudo[4252]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.183568] sudo[4252]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user, in 0.03 seconds) machine: must succeed: sudo -u usertest cp /nix/store/di777lvvzjra3wvdfd1d5vn7idzslns8-migrated.service ~usertest/.local/share/systemd/user/migrated.service machine # [ 47.203437] sudo[4258]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/di777lvvzjra3wvdfd1d5vn7idzslns8-migrated.service /home/usertest/.local/share/systemd/user/migrated.service machine # [ 47.211275] sudo[4258]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.217131] sudo[4258]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/di777lvvzjra3wvdfd1d5vn7idzslns8-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 47.236441] sudo[4264]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload machine # [ 47.243760] sudo[4264]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.249513] systemd[621]: Reload requested from client PID 4269 ('systemctl')... machine # [ 47.250918] systemd[621]: Reloading... machine # [ 47.323666] systemd[621]: Reloading finished in 71 ms. machine # [ 47.328806] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 47.336369] sudo[4264]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 47.357415] sudo[4274]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service machine # [ 47.364962] sudo[4274]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.375436] systemd[621]: Starting migrated.service... machine # [ 47.391057] systemd[621]: Finished migrated.service. machine # [ 47.393928] sudo[4274]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 47.413799] sudo[4282]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 47.423470] sudo[4282]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.430540] sudo[4282]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 47.449944] sudo[4288]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 47.457310] sudo[4288]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.462892] sudo[4288]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 47.481983] sudo[4294]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 47.489540] sudo[4294]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.504354] sudo[4294]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 47.684377] nixos[4303]: switching to system configuration /nix/store/k8izmwrszg78l61baf9pfmhzx35qk9h9-nixos-system-machine-test machine # [ 47.687720] systemd[1]: Stopped target Remote File Systems. machine # [ 47.688787] systemd[1]: Stopped target Local File Systems. machine # [ 47.909904] systemd[1]: Reload requested from client PID 4303 ('.switch-to-conf') (unit backdoor.service)... machine # [ 47.911587] systemd[1]: Reloading... machine # [ 48.141076] systemd[1]: Reloading finished in 227 ms. machine # [ 48.206107] systemd[621]: Reexecution requested from client PID 4372 ('switch-to-confi')... machine # [ 48.207327] systemd[621]: Reexecuting. machine # [ 48.226965] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.293101] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.298440] systemd[621]: Reload requested from client PID 4372 ('switch-to-confi')... machine # [ 48.300241] systemd[621]: Reloading... machine # [ 48.373088] systemd[621]: Reloading finished in 71 ms. machine # [ 48.376832] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.383309] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 48.385098] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 48.387341] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 48.406649] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 48.408978] systemd[621]: Reload requested from client PID 4372 ('switch-to-confi')... machine # [ 48.411103] systemd[621]: Reloading... machine # [ 48.483129] systemd[621]: Reloading finished in 72 ms. machine # [ 48.488864] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.494508] systemd[621]: Stopped migrated.service. machine # [ 48.495342] systemd[621]: Stopping migrated.service... machine # [ 48.497664] systemd[621]: Starting migrated.service... machine # [ 48.513451] systemd[621]: Finished migrated.service. machine # [ 48.518857] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 48.520310] systemd[1]: Stopping Reactivate sysinit units... machine # [ 48.521605] systemd[1]: Reached target Reactivate sysinit units. machine # [ 48.527511] systemd[1]: Reached target Local File Systems. machine # [ 48.529173] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 48.532167] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 48.534326] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 48.535688] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 48.537726] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 48.539225] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.540440] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.547202] systemd[1]: Reached target Remote File Systems. machine # [ 48.550632] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.552565] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 48.675582] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 48.677268] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 48.680993] systemd[1]: Starting linger-users.service... machine # [ 48.709152] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 48.711269] systemd[1]: Finished linger-users.service. machine # [ 48.968350] nixos[4303]: finished switching to system configuration /nix/store/k8izmwrszg78l61baf9pfmhzx35qk9h9-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.47 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 49.013595] sudo[4446]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 49.027597] sudo[4446]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 49.039140] sudo[4446]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 49.059166] sudo[4452]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 49.066703] sudo[4452]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 49.079391] sudo[4452]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 49.099278] sudo[4458]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 49.106895] sudo[4458]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 49.112415] sudo[4458]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 49.294321] nixos[4467]: switching to system configuration /nix/store/k8izmwrszg78l61baf9pfmhzx35qk9h9-nixos-system-machine-test machine # [ 49.297495] systemd[1]: Stopped target Remote File Systems. machine # [ 49.298574] systemd[1]: Stopped target Local File Systems. machine # [ 49.501519] systemd[1]: Reload requested from client PID 4467 ('.switch-to-conf') (unit backdoor.service)... machine # [ 49.503266] systemd[1]: Reloading... machine # [ 49.735565] systemd[1]: Reloading finished in 230 ms. machine # [ 49.807280] systemd[621]: Reexecution requested from client PID 4536 ('switch-to-confi')... machine # [ 49.808610] systemd[621]: Reexecuting. machine # [ 49.830190] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.901083] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.906372] systemd[621]: Reload requested from client PID 4536 ('switch-to-confi')... machine # [ 49.908197] systemd[621]: Reloading... machine # [ 49.980061] systemd[621]: Reloading finished in 70 ms. machine # [ 49.985867] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.992052] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 49.993387] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 49.995179] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 50.014095] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 50.017057] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 50.018627] systemd[1]: Stopping Reactivate sysinit units... machine # [ 50.019790] systemd[1]: Reached target Reactivate sysinit units. machine # [ 50.022416] systemd[1]: Reached target Remote File Systems. machine # [ 50.025591] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 50.027303] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 50.029411] systemd[1]: Reached target Local File Systems. machine # [ 50.033177] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 50.037318] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 50.038172] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 50.040264] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 50.043234] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 50.045383] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 50.046592] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 50.171882] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 50.174631] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 50.177717] systemd[1]: Starting linger-users.service... machine # [ 50.204383] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 50.206079] systemd[1]: Finished linger-users.service. machine # [ 50.463372] nixos[4467]: finished switching to system configuration /nix/store/k8izmwrszg78l61baf9pfmhzx35qk9h9-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.36 seconds) machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd machine # [ 50.511316] sudo[4604]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd machine # [ 50.525914] sudo[4604]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 50.535501] sudo[4604]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 50.555294] sudo[4610]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload machine # [ 50.562909] sudo[4610]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 50.568409] systemd[621]: Reload requested from client PID 4615 ('systemctl')... machine # [ 50.569595] systemd[621]: Reloading... machine # [ 50.642212] systemd[621]: Reloading finished in 70 ms. machine # [ 50.647826] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 50.656545] sudo[4610]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 50.833900] nixos[4623]: switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test machine # [ 50.836424] systemd[1]: Stopped target Remote File Systems. machine # [ 50.837816] systemd[1]: Stopped target Local File Systems. machine # [ 51.037673] systemd[1]: Reload requested from client PID 4623 ('.switch-to-conf') (unit backdoor.service)... machine # [ 51.039326] systemd[1]: Reloading... machine # [ 51.264829] systemd[1]: Reloading finished in 223 ms. machine # [ 51.324955] systemd[621]: Stopped migrated.service. machine # [ 51.327294] systemd[621]: Reexecution requested from client PID 4692 ('switch-to-confi')... machine # [ 51.328793] systemd[621]: Reexecuting. machine # [ 51.348037] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.418292] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.423917] systemd[621]: Reload requested from client PID 4692 ('switch-to-confi')... machine # [ 51.425553] systemd[621]: Reloading... machine # [ 51.498195] systemd[621]: Reloading finished in 71 ms. machine # [ 51.503808] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.511066] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 51.511956] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 51.513773] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 51.533246] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 51.536722] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 51.537710] systemd[1]: Stopping Reactivate sysinit units... machine # [ 51.538806] systemd[1]: Reached target Reactivate sysinit units. machine # [ 51.539948] systemd[1]: Reached target Local File Systems. machine # [ 51.543239] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 51.546574] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 51.548171] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 51.549385] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 51.551343] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 51.552796] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.556515] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.559903] systemd[1]: Reached target Remote File Systems. machine # [ 51.569427] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.570789] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 51.688961] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 51.690577] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 51.694285] systemd[1]: Starting linger-users.service... machine # [ 51.721394] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 51.722809] systemd[1]: Finished linger-users.service. machine # [ 51.980193] nixos[4623]: finished switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.33 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 52.027572] sudo[4760]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 52.041515] sudo[4760]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.048076] sudo[4760]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds) machine: must succeed: sudo -u usertest cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 52.067790] sudo[4766]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 52.075543] sudo[4766]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.081413] sudo[4766]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 52.101259] sudo[4772]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 52.108851] sudo[4772]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.114502] sudo[4772]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 52.133988] sudo[4778]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload machine # [ 52.141305] sudo[4778]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.146995] systemd[621]: Reload requested from client PID 4783 ('systemctl')... machine # [ 52.148475] systemd[621]: Reloading... machine # [ 52.221442] systemd[621]: Reloading finished in 71 ms. machine # [ 52.226812] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.234267] sudo[4778]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 52.254850] sudo[4788]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service machine # [ 52.262299] sudo[4788]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.271296] systemd[621]: Starting migrated.service... machine # [ 52.287950] systemd[621]: Finished migrated.service. machine # [ 52.290794] sudo[4788]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 52.310642] sudo[4796]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 52.317979] sudo[4796]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.324772] sudo[4796]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 52.344431] sudo[4802]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 52.351822] sudo[4802]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.357386] sudo[4802]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 52.376792] sudo[4808]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 52.384329] sudo[4808]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.395445] sudo[4808]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 52.572865] nixos[4817]: switching to system configuration /nix/store/k8izmwrszg78l61baf9pfmhzx35qk9h9-nixos-system-machine-test machine # [ 52.576496] systemd[1]: Stopped target Local File Systems. machine # [ 52.577618] systemd[1]: Stopped target Remote File Systems. machine # [ 52.776928] systemd[1]: Reload requested from client PID 4817 ('.switch-to-conf') (unit backdoor.service)... machine # [ 52.778644] systemd[1]: Reloading... machine # [ 53.029778] systemd[1]: Reloading finished in 249 ms. machine # [ 53.088863] systemd[621]: Reexecution requested from client PID 4886 ('switch-to-confi')... machine # [ 53.091355] systemd[621]: Reexecuting. machine # [ 53.111919] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.186260] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.191436] systemd[621]: Reload requested from client PID 4886 ('switch-to-confi')... machine # [ 53.192690] systemd[621]: Reloading... machine # [ 53.265659] systemd[621]: Reloading finished in 71 ms. machine # [ 53.270814] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.276937] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 53.278058] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 53.280332] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 53.298983] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 53.300600] systemd[621]: Reload requested from client PID 4886 ('switch-to-confi')... machine # [ 53.302325] systemd[621]: Reloading... machine # [ 53.372730] systemd[621]: Reloading finished in 69 ms. machine # [ 53.377830] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.386559] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 53.387589] systemd[1]: Stopping Reactivate sysinit units... machine # [ 53.388379] systemd[1]: Reached target Reactivate sysinit units. machine # [ 53.391605] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.393203] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 53.395341] systemd[1]: Reached target Remote File Systems. machine # [ 53.396872] systemd[1]: Reached target Local File Systems. machine # [ 53.405078] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 53.407341] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 53.409199] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 53.411237] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 53.413964] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 53.415963] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.418101] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.541755] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 53.543934] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 53.547062] systemd[1]: Starting linger-users.service... machine # [ 53.574308] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 53.575751] systemd[1]: Finished linger-users.service. machine # [ 53.833401] nixos[4817]: finished switching to system configuration /nix/store/k8izmwrszg78l61baf9pfmhzx35qk9h9-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.44 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 53.878880] sudo[4958]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 53.893987] sudo[4958]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 53.905738] sudo[4958]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 53.926375] sudo[4964]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 53.933943] sudo[4964]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 53.939533] sudo[4964]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd machine # [ 53.959166] sudo[4970]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd machine # [ 53.966461] sudo[4970]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 53.971767] sudo[4970]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 53.991317] sudo[4976]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload machine # [ 53.998650] sudo[4976]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 54.004375] systemd[621]: Reload requested from client PID 4981 ('systemctl')... machine # [ 54.005502] systemd[621]: Reloading... machine # [ 54.077762] systemd[621]: Reloading finished in 70 ms. machine # [ 54.081816] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.089290] sudo[4976]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service machine # [ 54.110353] sudo[4986]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user stop migrated.service machine # [ 54.117738] sudo[4986]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 54.125658] systemd[621]: Stopped migrated.service. machine # [ 54.128387] sudo[4986]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service, in 0.04 seconds) machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 54.305207] nixos[4995]: switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test machine # [ 54.307576] systemd[1]: Stopped target Local File Systems. machine # [ 54.309219] systemd[1]: Stopped target Remote File Systems. machine # [ 54.509183] systemd[1]: Reload requested from client PID 4995 ('.switch-to-conf') (unit backdoor.service)... machine # [ 54.510880] systemd[1]: Reloading... machine # [ 54.734672] systemd[1]: Reloading finished in 222 ms. machine # [ 54.796749] systemd[621]: Reexecution requested from client PID 5064 ('switch-to-confi')... machine # [ 54.798060] systemd[621]: Reexecuting. machine # [ 54.819140] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.889867] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.895942] systemd[621]: Reload requested from client PID 5064 ('switch-to-confi')... machine # [ 54.897803] systemd[621]: Reloading... machine # [ 54.969928] systemd[621]: Reloading finished in 71 ms. machine # [ 54.975810] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.982254] systemd[621]: Stopped Run user-specific NixOS activation. machine # [ 54.983109] systemd[621]: Stopping Run user-specific NixOS activation... machine # [ 54.985230] systemd[621]: Starting Run user-specific NixOS activation... machine # [ 55.003986] systemd[621]: Finished Run user-specific NixOS activation. machine # [ 55.006946] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 55.008141] systemd[1]: Stopping Reactivate sysinit units... machine # [ 55.009246] systemd[1]: Reached target Reactivate sysinit units. machine # [ 55.012056] systemd[1]: Reached target Local File Systems. machine # [ 55.014308] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 55.017067] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 55.018744] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 55.021078] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 55.023086] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 55.024500] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.025667] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.031776] systemd[1]: Reached target Remote File Systems. machine # [ 55.040309] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.041830] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 55.160371] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 55.162242] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 55.165868] systemd[1]: Starting linger-users.service... machine # [ 55.193356] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 55.195087] systemd[1]: Finished linger-users.service. machine # [ 55.452282] nixos[4995]: finished switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.33 seconds) (finished: subtest: user services, in 36.96 seconds) (finished: run the VM test script, in 56.50 seconds) test script finished in 56.56s 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:10Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-10-19T03:01:10Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-10-19T03:01:10Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.18 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