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.BnEEUD0la1', 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: 34a6b496-c3f9-4684-ae1e-e673c615f026 machine # Superblock backups stored on blocks: machine # 32768, 98304, 163840, 229376 machine # machine # Allocating group tables: 0/8 done machine # Writing inode tables: 0/8 done machine # Creating journal (8192 blocks): done machine # Writing superblocks and filesystem accounting information: 0/8 done machine # machine # Virtualisation disk image created. machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) machine # machine # machine # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA00 machine # Press Ctrl-B to configure iPXE (PCI 00:02.0)... machine # machine # machine # machine # machine # iPXE (http://ipxe.org) 00:08.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB00 machine # Press Ctrl-B to configure iPXE (PCI 00:08.0)... machine # machine # machine # Booting from ROM... machine # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.48 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Aug 28 06:22:54 UTC 2026 machine # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/init regInfo=/nix/store/gaaawqkmab3c0q4xif2dqiwb4az1kjp6-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 470585301 cycles machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns machine # [ 0.000005] tsc: Detected 2400.010 MHz processor machine # [ 0.000809] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 machine # [ 0.000836] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs machine # [ 0.000839] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT machine # [ 0.002742] found SMP MP-table at [mem 0x000f5450-0x000f545f] machine # [ 0.002753] Using GB pages for direct mapping machine # [ 0.002803] RAMDISK: [mem 0x3e429000-0x3ffcffff] machine # [ 0.002813] ACPI: Early table checksum verification disabled machine # [ 0.002815] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) machine # [ 0.002819] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002822] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002829] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002831] ACPI: FACS 0x000000003FFE0000 000040 machine # [ 0.002832] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002834] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002836] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002837] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002839] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d] machine # [ 0.002840] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219] machine # [ 0.002840] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f] machine # [ 0.002841] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385] machine # [ 0.002841] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd] machine # [ 0.002841] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9] machine # [ 0.002842] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421] machine # [ 0.003069] 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.005299] Zone ranges: machine # [ 0.005300] DMA [mem 0x0000000000001000-0x0000000000ffffff] machine # [ 0.005301] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff] machine # [ 0.005303] Normal empty machine # [ 0.005303] Device empty machine # [ 0.005304] Movable zone start for each node machine # [ 0.005305] Early memory node ranges machine # [ 0.005305] node 0: [mem 0x0000000000001000-0x000000000009efff] machine # [ 0.005306] node 0: [mem 0x0000000000100000-0x000000003ffd7fff] machine # [ 0.005307] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff] machine # [ 0.005327] On node 0, zone DMA: 1 pages in unavailable ranges machine # [ 0.005574] On node 0, zone DMA: 97 pages in unavailable ranges machine # [ 0.022179] On node 0, zone DMA32: 40 pages in unavailable ranges machine # [ 0.022637] ACPI: PM-Timer IO Port: 0x608 machine # [ 0.022647] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) machine # [ 0.022674] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 machine # [ 0.022677] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) machine # [ 0.022678] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) machine # [ 0.022679] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) machine # [ 0.022680] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) machine # [ 0.022681] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) machine # [ 0.022683] ACPI: Using ACPI (MADT) for SMP configuration information machine # [ 0.022685] ACPI: HPET id: 0x8086a201 base: 0xfed00000 machine # [ 0.022687] TSC deadline timer available machine # [ 0.022691] CPU topo: Max. logical packages: 1 machine # [ 0.022692] CPU topo: Max. logical dies: 1 machine # [ 0.022692] CPU topo: Max. dies per package: 1 machine # [ 0.022695] CPU topo: Max. threads per core: 1 machine # [ 0.022696] CPU topo: Num. cores per package: 1 machine # [ 0.022696] CPU topo: Num. threads per package: 1 machine # [ 0.022697] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs machine # [ 0.022712] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() machine # [ 0.022742] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] machine # [ 0.022744] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] machine # [ 0.022745] [mem 0x40000000-0xafffffff] available for PCI devices machine # [ 0.022746] Booting paravirtualized kernel on KVM machine # [ 0.022749] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns machine # [ 0.027179] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 machine # [ 0.029259] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152 machine # [ 0.029299] kvm-guest: PV spinlocks disabled, single CPU machine # [ 0.029301] 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/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/init regInfo=/nix/store/gaaawqkmab3c0q4xif2dqiwb4az1kjp6-closure-info/registration console=ttyS0,115200n8 console=tty0 machine # [ 0.029390] Unknown kernel command line parameters "regInfo=/nix/store/gaaawqkmab3c0q4xif2dqiwb4az1kjp6-closure-info/registration", will be passed to user space. machine # [ 0.029403] random: crng init done machine # [ 0.029404] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes machine # [ 0.029425] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.029437] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.029466] Fallback order for Node 0: 0 machine # [ 0.029469] Built 1 zonelists, mobility grouping on. Total pages: 262006 machine # [ 0.029470] Policy zone: DMA32 machine # [ 0.031801] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.034120] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 machine # [ 0.036362] allocated 2097152 bytes of page_ext machine # [ 0.045443] ftrace: allocating 48733 entries in 192 pages machine # [ 0.045445] ftrace: allocated 192 pages with 2 groups machine # [ 0.046240] Dynamic Preempt: lazy machine # [ 0.046409] rcu: Preemptible hierarchical RCU implementation. machine # [ 0.046410] rcu: RCU event tracing is enabled. machine # [ 0.046411] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. machine # [ 0.046412] Trampoline variant of Tasks RCU enabled. machine # [ 0.046412] Rude variant of Tasks RCU enabled. machine # [ 0.046413] Tracing variant of Tasks RCU enabled. machine # [ 0.046413] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. machine # [ 0.046414] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 machine # [ 0.046434] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.046436] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.046436] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.051074] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16 machine # [ 0.051560] rcu: srcu_init: Setting srcu_struct sizes based on contention. machine # [ 0.051567] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns machine # [ 0.051670] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.056059] Console: colour VGA+ 80x25 machine # [ 0.056062] printk: legacy console [tty0] enabled machine # [ 0.085235] printk: legacy console [ttyS0] enabled machine # [ 0.194092] ACPI: Core revision 20250807 machine # [ 0.195219] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns machine # [ 0.197262] APIC: Switch to symmetric I/O mode setup machine # [ 0.198565] x2apic enabled machine # [ 0.199509] APIC: Switched APIC routing to: physical x2apic machine # [ 0.201827] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 machine # [ 0.202914] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns machine # [ 0.204669] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400010) machine # [ 0.205752] x86/cpu: User Mode Instruction Prevention (UMIP) activated machine # [ 0.207755] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 machine # [ 0.208666] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 machine # [ 0.209669] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto machine # [ 0.210666] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl machine # [ 0.211666] Transient Scheduler Attacks: Vulnerable: No microcode machine # [ 0.212665] Spectre V2 : Mitigation: Enhanced / Automatic IBRS machine # [ 0.213666] Speculative Return Stack Overflow: Mitigation: Safe RET machine # [ 0.214665] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization machine # [ 0.216675] Spectre V2 : Enabling IBPB for BPF machine # [ 0.217667] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier machine # [ 0.218666] active return thunk: srso_alias_return_thunk machine # [ 0.220370] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' machine # [ 0.221666] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' machine # [ 0.222666] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' machine # [ 0.223667] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' machine # [ 0.224666] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' machine # [ 0.225666] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' machine # [ 0.226666] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' machine # [ 0.227667] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 machine # [ 0.229457] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 machine # [ 0.230453] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 machine # [ 0.231445] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 machine # [ 0.232473] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 machine # [ 0.233457] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format. machine # [ 0.268599] Freeing SMP alternatives memory: 44K machine # [ 0.269252] pid_max: default: 32768 minimum: 301 machine # [ 0.269753] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.270775] landlock: Up and running. machine # [ 0.272137] Yama: becoming mindful. machine # [ 0.272889] LSM support for eBPF active machine # [ 0.273811] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.275692] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.277868] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1) machine # [ 0.279206] Performance Events: Fam17h+ core perfctr, AMD PMU driver. machine # [ 0.279674] ... version: 2 machine # [ 0.280429] ... bit width: 48 machine # [ 0.280669] ... generic counters: 6 machine # [ 0.281434] ... generic bitmap: 000000000000003f machine # [ 0.281668] ... fixed-purpose counters: 0 machine # [ 0.282383] ... fixed-purpose bitmap: 0000000000000000 machine # [ 0.282668] ... value mask: 0000ffffffffffff machine # [ 0.283629] ... max period: 00007fffffffffff machine # [ 0.284412] ... global_ctrl mask: 000000000000003f machine # [ 0.284765] signal: max sigframe size: 3376 machine # [ 0.285639] rcu: Hierarchical SRCU implementation. machine # [ 0.286325] rcu: Max phase no-delay instances is 400. machine # [ 0.291123] smp: Bringing up secondary CPUs ... machine # [ 0.291682] smp: Brought up 1 node, 1 CPU machine # [ 0.292451] smpboot: Total of 1 processors activated (4800.02 BogoMIPS) machine # [ 0.293709] Memory: 941792K/1048024K available (17215K kernel code, 2726K rwdata, 13580K rodata, 3644K init, 2988K bss, 98840K reserved, 0K cma-reserved) machine # [ 0.294885] devtmpfs: initialized machine # [ 0.295656] x86/mm: Memory block size: 128MB machine # [ 0.297156] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.297699] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.298778] pinctrl core: initialized pinctrl subsystem machine # [ 0.299948] PM: RTC time: 02:38:38, date: 2026-09-04 machine # [ 0.303808] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.305058] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.305686] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.306804] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.307680] audit: initializing netlink subsys (disabled) machine # [ 0.308844] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.308846] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.309671] audit: type=2000 audit(1788489519.153:1): state=initialized audit_enabled=0 res=1 machine # [ 0.311669] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.311670] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.312668] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.313686] cpuidle: using governor menu machine # [ 0.315941] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 machine # [ 0.316898] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] machine # [ 0.317671] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry machine # [ 0.318681] PCI: Using configuration type 1 for base access machine # [ 0.319813] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. machine # [ 0.324893] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.325669] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.330669] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.331668] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.342051] ACPI: Added _OSI(Module Device) machine # [ 0.342670] ACPI: Added _OSI(Processor Device) machine # [ 0.343450] ACPI: Added _OSI(Processor Aggregator Device) machine # [ 0.352065] ACPI: 1 ACPI AML tables successfully acquired and loaded machine # [ 0.356082] ACPI: Interpreter enabled machine # [ 0.356683] ACPI: PM: (supports S0 S3 S4 S5) machine # [ 0.357436] ACPI: Using IOAPIC for interrupt routing machine # [ 0.359757] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug machine # [ 0.360671] PCI: Using E820 reservations for host bridge windows machine # [ 0.363818] ACPI: Enabled 2 GPEs in block 00 to 3F machine # [ 0.371422] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) machine # [ 0.371675] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] machine # [ 0.372748] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] machine # [ 0.373790] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] machine # [ 0.375025] PCI host bridge to bus 0000:00 machine # [ 0.375673] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] machine # [ 0.376668] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] machine # [ 0.377668] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] machine # [ 0.378668] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window] machine # [ 0.379668] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] machine # [ 0.380668] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] machine # [ 0.381669] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.382724] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.384112] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint machine # [ 0.386706] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] machine # [ 0.387681] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] machine # [ 0.388668] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] machine # [ 0.389989] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] machine # [ 0.391526] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.393676] pci 0000:00:02.0: BAR 0 [io 0xc140-0xc15f] machine # [ 0.394571] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] machine # [ 0.395452] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] machine # [ 0.396565] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] machine # [ 0.398025] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.400651] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f] machine # [ 0.401348] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] machine # [ 0.401690] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] machine # [ 0.403230] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.405441] pci 0000:00:04.0: BAR 0 [io 0xc080-0xc0bf] machine # [ 0.405675] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] machine # [ 0.406679] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] machine # [ 0.408227] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.409675] pci 0000:00:05.0: BAR 0 [io 0xc180-0xc19f] machine # [ 0.410571] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] machine # [ 0.411463] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref] machine # [ 0.413140] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.414872] pci 0000:00:06.0: BAR 0 [io 0xc1a0-0xc1bf] machine # [ 0.415675] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] machine # [ 0.416684] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref] machine # [ 0.418250] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.419690] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f] machine # [ 0.420571] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] machine # [ 0.421444] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref] machine # [ 0.423192] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.425676] pci 0000:00:08.0: BAR 0 [io 0xc1c0-0xc1df] machine # [ 0.426567] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] machine # [ 0.427453] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref] machine # [ 0.428559] pci 0000:00:08.0: ROM [mem 0xfeb80000-0xfebbffff pref] machine # [ 0.430039] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.431680] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] machine # [ 0.432690] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref] machine # [ 0.434215] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.435692] pci 0000:00:0a.0: BAR 0 [io 0xc0c0-0xc0ff] machine # [ 0.436582] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] machine # [ 0.437474] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref] machine # [ 0.439118] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.440892] pci 0000:00:0b.0: BAR 0 [io 0xc1e0-0xc1ff] machine # [ 0.441675] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff] machine # [ 0.442614] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref] machine # [ 0.444049] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.445201] pci 0000:00:1d.0: BAR 4 [io 0xc200-0xc21f] machine # [ 0.445883] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.447223] pci 0000:00:1d.1: BAR 4 [io 0xc220-0xc23f] machine # [ 0.447859] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.451173] pci 0000:00:1d.2: BAR 4 [io 0xc240-0xc25f] machine # [ 0.451927] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.453552] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff] machine # [ 0.454653] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint machine # [ 0.455937] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO machine # [ 0.456919] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint machine # [ 0.459157] pci 0000:00:1f.2: BAR 4 [io 0xc260-0xc27f] machine # [ 0.459675] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff] machine # [ 0.460932] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint machine # [ 0.462301] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] machine # [ 0.465628] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 machine # [ 0.466498] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 machine # [ 0.467761] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 machine # [ 0.468757] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 machine # [ 0.469768] ACPI: PCI: Interrupt link LNKE configured for IRQ 10 machine # [ 0.470764] ACPI: PCI: Interrupt link LNKF configured for IRQ 10 machine # [ 0.471771] ACPI: PCI: Interrupt link LNKG configured for IRQ 11 machine # [ 0.472765] ACPI: PCI: Interrupt link LNKH configured for IRQ 11 machine # [ 0.473707] ACPI: PCI: Interrupt link GSIA configured for IRQ 16 machine # [ 0.474675] ACPI: PCI: Interrupt link GSIB configured for IRQ 17 machine # [ 0.475625] ACPI: PCI: Interrupt link GSIC configured for IRQ 18 machine # [ 0.476400] ACPI: PCI: Interrupt link GSID configured for IRQ 19 machine # [ 0.476684] ACPI: PCI: Interrupt link GSIE configured for IRQ 20 machine # [ 0.477687] ACPI: PCI: Interrupt link GSIF configured for IRQ 21 machine # [ 0.478645] ACPI: PCI: Interrupt link GSIG configured for IRQ 22 machine # [ 0.479436] ACPI: PCI: Interrupt link GSIH configured for IRQ 23 machine # [ 0.480740] iommu: Default domain type: Translated machine # [ 0.481503] iommu: DMA domain TLB invalidation policy: lazy mode machine # [ 0.482652] ACPI: bus type USB registered machine # [ 0.483197] usbcore: registered new interface driver usbfs machine # [ 0.483686] usbcore: registered new interface driver hub machine # [ 0.484544] usbcore: registered new device driver usb machine # [ 0.486268] NetLabel: Initializing machine # [ 0.486688] NetLabel: domain hash size = 128 machine # [ 0.487397] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO machine # [ 0.487745] NetLabel: unlabeled traffic allowed by default machine # [ 0.488631] PCI: Using ACPI for IRQ routing machine # [ 0.533940] pci 0000:00:01.0: vgaarb: setting as boot VGA device machine # [ 0.534664] pci 0000:00:01.0: vgaarb: bridge control possible machine # [ 0.534664] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none machine # [ 0.534673] vgaarb: loaded machine # [ 0.535410] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 machine # [ 0.535668] hpet0: 3 comparators, 64-bit 100.000000 MHz counter machine # [ 0.539759] clocksource: Switched to clocksource kvm-clock machine # [ 0.543145] VFS: Disk quotas dquot_6.6.0 machine # [ 0.543828] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.545177] pnp: PnP ACPI init machine # [ 0.545947] ACPI: IRQ 4 override to edge(!), high(!) machine # [ 0.546913] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved machine # [ 0.548453] pnp: PnP ACPI: found 6 devices machine # [ 0.555974] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns machine # [ 0.557440] clocksource: Switched to clocksource acpi_pm machine # [ 0.558499] NET: Registered PF_INET protocol family machine # [ 0.559590] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.576600] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.578235] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.579595] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.580950] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.582276] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.583455] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.584802] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.585970] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.587286] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.588316] NET: Registered PF_XDP protocol family machine # [ 0.589178] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] machine # [ 0.590218] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] machine # [ 0.591287] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] machine # [ 0.592461] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window] machine # [ 0.593626] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] machine # [ 0.594812] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] machine # [ 0.596638] ACPI: \_SB_.GSIA: Enabled at IRQ 16 machine # [ 0.598979] ACPI: \_SB_.GSIB: Enabled at IRQ 17 machine # [ 0.601009] ACPI: \_SB_.GSIC: Enabled at IRQ 18 machine # [ 0.603019] ACPI: \_SB_.GSID: Enabled at IRQ 19 machine # [ 0.604857] PCI: CLS 0 bytes, default 64 machine # [ 0.605808] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns machine # [ 0.607664] Trying to unpack rootfs image as initramfs... machine # [ 0.653554] Initialise system trusted keyrings machine # [ 0.654582] workingset: timestamp_bits=40 max_order=18 bucket_order=0 machine # [ 0.678433] Key type asymmetric registered machine # [ 0.679156] Asymmetric key parser 'x509' registered machine # [ 0.679966] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) machine # [ 0.683281] io scheduler mq-deadline registered machine # [ 0.684014] io scheduler kyber registered machine # [ 0.687660] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.688906] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A machine # [ 0.692908] Linux agpgart interface v0.103 machine # [ 0.693678] ACPI: bus type drm_connector registered machine # [ 0.697605] usbcore: registered new interface driver usbserial_generic machine # [ 0.698651] usbserial: USB Serial support registered for generic machine # [ 0.702133] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS. machine # [ 0.702133] Please enable it if your BIOS has the CPPC option. machine # [ 0.704316] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled machine # [ 0.708255] drop_monitor: Initializing network drop monitor service machine # [ 0.709421] NET: Registered PF_INET6 protocol family machine # [ 0.712570] Segment Routing with IPv6 machine # [ 0.715133] In-situ OAM (IOAM) with IPv6 machine # [ 0.716206] IPI shorthand broadcast: enabled machine # [ 0.724670] sched_clock: Marking stable (577014299, 147098042)->(797080379, -72968038) machine # [ 0.730232] registered taskstats version 1 machine # [ 0.731175] Loading compiled-in X.509 certificates machine # [ 0.747586] Demotion targets for Node 0: null machine # [ 0.750168] Key type .fscrypt registered machine # [ 0.750816] Key type fscrypt-provisioning registered machine # [ 0.751729] ima: No TPM chip found, activating TPM-bypass! machine # [ 0.754131] ima: Allocated hash algorithm: sha1 machine # [ 0.754905] ima: No architecture policies found machine # [ 0.759130] PM: Magic number: 10:109:611 machine # [ 0.760691] RAS: Correctable Errors collector initialized. machine # [ 0.768793] clk: Disabling unused clocks machine # [ 0.771136] PM: genpd: Disabling unused power domains machine # [ 0.928367] Freeing initrd memory: 28316K machine # [ 0.931386] Freeing unused decrypted memory: 2028K machine # [ 0.934020] Freeing unused kernel image (initmem) memory: 3644K machine # [ 0.935048] Write protecting the kernel read-only data: 32768k machine # [ 0.936851] Freeing unused kernel image (text/rodata gap) memory: 1216K machine # [ 0.938320] Freeing unused kernel image (rodata/data gap) memory: 756K machine # [ 0.990172] x86/mm: Checked W+X mappings: passed, no W+X pages found. machine # [ 0.991329] Run /init as init process machine # [ 1.002184] systemd[1]: Inserted module 'autofs4' machine # [ 1.018624] fuse: init (API version 7.45) machine # [ 1.024375] ACPI: \_SB_.GSIG: Enabled at IRQ 22 machine # [ 1.026720] ACPI: \_SB_.GSIH: Enabled at IRQ 23 machine # [ 1.030017] ACPI: \_SB_.GSIE: Enabled at IRQ 20 machine # [ 1.032703] ACPI: \_SB_.GSIF: Enabled at IRQ 21 machine # [ 1.062322] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.398947] systemd[1]: systemd 262-175b52e running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) machine # [ 1.411152] systemd[1]: Detected virtualization kvm. machine # [ 1.413277] systemd[1]: Detected architecture x86-64. machine # [ 1.415444] systemd[1]: Running in initrd. machine # [ 1.418012] systemd[1]: Initializing machine ID from random generator. machine # [ 1.420963] systemd[1]: Hostname set to . machine # [ 1.512492] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.550650] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.554486] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.555697] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.557155] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.558279] systemd[1]: Reached target Path Units. machine # [ 1.559150] systemd[1]: Reached target Slice Units. machine # [ 1.560019] systemd[1]: Reached target Swaps. machine # [ 1.560921] systemd[1]: Reached target Timer Units. machine # [ 1.561962] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.563250] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.564457] systemd[1]: Listening on Journal Sockets. machine # [ 1.565471] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.566457] systemd[1]: Reached target Socket Units. machine # [ 1.569195] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.572003] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 1.581203] systemd[1]: Starting Load Kernel Module configfs... machine # [ 1.593696] systemd[1]: Starting Journal Service... machine # [ 1.604234] systemd[1]: Starting Load Kernel Modules... machine # [ 1.611205] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.631457] netfs: FS-Cache loaded machine # [ 1.636264] 9pnet: Installing 9P2000 support machine # [ 1.646206] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.663314] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.668961] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 1.679553] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 1.684657] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 1.689169] systemd-journald[67]: Collecting audit messages is disabled. machine # [ 1.691490] systemd[1]: Finished Load Kernel Module configfs. machine # [ 1.697589] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config machine # [ 1.703419] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.709193] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.711153] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.740660] systemd[1]: Finished Load Kernel Modules. machine # [ 1.747321] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.759068] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.769328] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.782361] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.800704] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.655211] systemd-modules-load[68]: Inserted module 'dm_mod' machine # [ 1.657380] systemd-modules-load[68]: Inserted module 'virtio_balloon' machine # [ 1.805580] systemd[1]: Started Journal Service. machine # [ 1.659390] systemd-modules-load[68]: Inserted module 'virtio_gpu' machine # [ 1.669108] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.671079] systemd[1]: Reached target Local File Systems. machine # [ 1.674063] systemd[1]: Starting Create System Files and Directories... machine # [ 1.679080] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.704207] systemd[1]: Finished Create System Files and Directories. machine # [ 1.727363] systemd-udevd[83]: Using default interface naming scheme 'v261'. machine # [ 1.753118] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 1.797099] systemd[1]: Finished Coldplug All udev Devices. machine # [ 1.797886] systemd[1]: Reached target System Initialization. machine # [ 1.798744] systemd[1]: Reached target Basic System. machine # [ 2.123448] virtio_blk virtio5: 1/0/0 default/read/poll queues machine # [ 2.128205] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 machine # [ 2.145793] serio: i8042 KBD port at 0x60,0x64 irq 1 machine # [ 2.151186] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 2.156276] serio: i8042 AUX port at 0x60,0x64 irq 12 machine # [ 2.170282] ehci-pci 0000:00:1d.7: EHCI Host Controller machine # [ 2.171196] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1 machine # [ 2.173065] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000 machine # [ 2.181147] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 machine # [ 2.184030] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18 machine # [ 2.187121] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.188818] usb usb1: Product: EHCI Host Controller machine # [ 2.190113] usb usb1: Manufacturer: Linux 6.18.48 ehci_hcd machine # [ 2.190835] usb usb1: SerialNumber: 0000:00:1d.7 machine # [ 2.193313] hub 1-0:1.0: USB hub found machine # [ 2.194966] hub 1-0:1.0: 6 ports detected machine # [ 2.199993] uhci_hcd 0000:00:1d.0: UHCI Host Controller machine # [ 2.200793] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2 machine # [ 2.214270] uhci_hcd 0000:00:1d.0: detected 2 ports machine # [ 2.215064] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c200 machine # [ 2.228788] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 2.229963] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.243851] usb usb2: Product: UHCI Host Controller machine # [ 2.248244] SCSI subsystem initialized machine # [ 2.254687] usb usb2: Manufacturer: Linux 6.18.48 uhci_hcd machine # [ 2.113407] (udev-worker)[86]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.263124] usb usb2: SerialNumber: 0000:00:1d.0 machine # [ 2.119072] systemd[1]: Starting Virtual Console Setup... machine # [ 2.267853] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 machine # [ 2.129827] (udev-worker)[93]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.281843] hub 2-0:1.0: USB hub found machine # [ 2.287421] hub 2-0:1.0: 2 ports detected machine # [ 2.143169] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.160974] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.162669] systemd[1]: Finished Virtual Console Setup. machine # [ 2.324499] uhci_hcd 0000:00:1d.1: UHCI Host Controller machine # [ 2.325241] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3 machine # [ 2.332867] uhci_hcd 0000:00:1d.1: detected 2 ports machine # [ 2.336239] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c220 machine # [ 2.340809] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 2.341966] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.202076] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 2.202961] systemd[1]: Reached target Initrd Root Device. machine # [ 2.350851] usb usb3: Product: UHCI Host Controller machine # [ 2.205107] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 2.353339] usb usb3: Manufacturer: Linux 6.18.48 uhci_hcd machine # [ 2.354070] usb usb3: SerialNumber: 0000:00:1d.1 machine # [ 2.360535] hub 3-0:1.0: USB hub found machine # [ 2.361535] hub 3-0:1.0: 2 ports detected machine # [ 2.370153] uhci_hcd 0000:00:1d.2: UHCI Host Controller machine # [ 2.370867] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4 machine # [ 2.373139] uhci_hcd 0000:00:1d.2: detected 2 ports machine # [ 2.373958] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c240 machine # [ 2.383006] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 2.384886] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.387153] usb usb4: Product: UHCI Host Controller machine # [ 2.387883] usb usb4: Manufacturer: Linux 6.18.48 uhci_hcd machine # [ 2.390737] usb usb4: SerialNumber: 0000:00:1d.2 machine # [ 2.396676] hub 4-0:1.0: USB hub found machine # [ 2.398302] hub 4-0:1.0: 2 ports detected machine # [ 2.252161] systemd-fsck[107]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 2.258990] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 2.409240] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode machine # [ 2.418279] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) machine # [ 2.423629] ahci 0000:00:1f.2: flags: 64bit ncq only machine # [ 2.431131] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 2.437738] scsi host0: ahci machine # [ 2.438629] scsi host1: ahci machine # [ 2.442802] scsi host2: ahci machine # [ 2.444832] scsi host3: ahci machine # [ 2.448076] scsi host4: ahci machine # [ 2.450128] scsi host5: ahci machine # [ 2.450711] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 44 lpm-pol 1 machine # [ 2.455130] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 44 lpm-pol 1 machine # [ 2.458338] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 44 lpm-pol 1 machine # [ 2.459635] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 44 lpm-pol 1 machine # [ 2.460816] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 44 lpm-pol 1 machine # [ 2.461970] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 44 lpm-pol 1 machine # [ 2.562498] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 machine # [ 2.565417] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 machine # [ 2.568839] usb 1-1: Product: QEMU USB Tablet machine # [ 2.570785] usb 1-1: Manufacturer: QEMU machine # [ 2.572813] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1 machine # [ 2.606830] hid: raw HID events driver (C) Jiri Kosina machine # [ 2.509958] systemd[1]: Mounting /sysroot... machine # [ 2.774758] ata4: SATA link down (SStatus 0 SControl 300) machine # [ 2.777064] ata5: SATA link down (SStatus 0 SControl 300) machine # [ 2.779642] ata6: SATA link down (SStatus 0 SControl 300) machine # [ 2.782000] ata2: SATA link down (SStatus 0 SControl 300) machine # [ 2.784543] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) machine # [ 2.787030] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 machine # [ 2.789408] ata3.00: applying bridge limits machine # [ 2.791427] ata1: SATA link down (SStatus 0 SControl 300) machine # [ 2.793579] ata3.00: configured for UDMA/100 machine # [ 2.796007] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 machine # [ 2.867943] usbcore: registered new interface driver usbhid machine # [ 2.874106] usbhid: USB HID core driver machine # [ 2.891501] 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.893257] 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.903425] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray machine # [ 2.912967] EXT4-fs (vda): mounted filesystem 34a6b496-c3f9-4684-ae1e-e673c615f026 r/w with ordered data mode. Quota mode: none. machine # [ 2.772272] systemd[1]: Mounted /sysroot. machine # [ 2.772917] systemd[1]: Reached target Initrd Root File System. machine # [ 2.920862] cdrom: Uniform CD-ROM driver Revision: 3.20 machine # [ 2.776358] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 2.795504] systemd-sysroot-fstab-check[139]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 2.798809] systemd[1]: Reload requested from client PID 139 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 2.801158] systemd[1]: Reloading... machine # [ 2.880043] systemd[1]: Reloading finished in 79 ms. machine # [ 2.890642] systemd-sysroot-fstab-check[139]: Requesting initrd-fs.target/start/replace... machine # [ 2.892967] systemd-sysroot-fstab-check[139]: Requesting swap.target/start/replace... machine # [ 2.898499] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 2.899910] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 2.901175] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 2.903542] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio machine # [ 3.512185] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.517322] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 3.526335] systemd[1]: Mounting /sysroot/run... machine # [ 3.535222] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.547698] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.734758] 9p: Installing v9fs 9p2000 file system support machine # [ 3.590219] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.597954] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.603448] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.604247] systemd[1]: Mounted /sysroot/run. machine # [ 3.606882] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.608160] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.617289] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.619654] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.620714] systemd[1]: Mounting /sysroot/nix/store... machine # [ 3.641495] systemd[1]: Mounted /sysroot/nix/store. machine # [ 3.643072] systemd[1]: Reached target Initrd File Systems. machine # [ 3.644740] systemd[1]: Starting Find NixOS closure... machine # [ 3.648354] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 3.665939] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 3.674133] systemd[1]: Finished Find NixOS closure. machine # [ 3.675444] systemd[1]: Reached target Initrd Default Target. machine # [ 3.677476] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 3.691319] systemd[1]: Stopped target Initrd Default Target. machine # [ 3.692566] systemd[1]: Stopped target Basic System. machine # [ 3.693533] systemd[1]: Stopped target Initrd Root Device. machine # [ 3.695064] systemd[1]: Stopped target Path Units. machine # [ 3.695869] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 3.697052] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 3.698087] systemd[1]: Stopped target Slice Units. machine # [ 3.699507] systemd[1]: Stopped target Socket Units. machine # [ 3.700599] systemd[1]: Stopped target System Initialization. machine # [ 3.701722] systemd[1]: Stopped target Swaps. machine # [ 3.702607] systemd[1]: Stopped target Timer Units. machine # [ 3.703665] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 3.705195] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 3.705991] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 3.707078] systemd[1]: Stopped Find NixOS closure. machine # [ 3.708108] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio machine # [ 3.710142] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.711105] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 3.712193] systemd[1]: Stopped Apply Kernel Variables. machine # [ 3.713498] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 3.715180] systemd[1]: Stopped Load Kernel Modules. machine # [ 3.716119] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 3.717364] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 3.718610] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 3.720081] systemd[1]: Stopped Create System Files and Directories. machine # [ 3.721251] systemd[1]: Stopped target Local File Systems. machine # [ 3.722416] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 3.724522] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 3.725514] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 3.727451] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 3.728466] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 3.729438] systemd[1]: Stopped Virtual Console Setup. machine # [ 3.736832] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 3.741212] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 3.742109] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 3.744151] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 3.751092] systemd[1]: Starting Cleanup udev Database... machine # [ 3.751938] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 3.754150] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 3.755162] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 3.757169] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 3.758166] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 3.759149] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 3.759967] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.762130] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.776892] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 3.778616] systemd[1]: Finished Cleanup udev Database. machine # [ 3.779900] systemd[1]: Reached target Switch Root. machine # [ 3.781766] systemd[1]: Starting NixOS Activation... machine # [ 3.896690] initrd-nixos-activation-start[192]: booting system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test machine # [ 3.944460] initrd-nixos-activation-start[192]: running activation script... machine # [ 4.328921] initrd-nixos-activation-start[215]: setting up /etc... machine # [ 4.577590] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 4.579183] systemd[1]: Finished NixOS Activation. machine # [ 4.581160] systemd[1]: Starting Switch Root... machine # [ 4.593717] systemd[1]: Switching root. machine # [ 4.872402] systemd-journald[67]: Received SIGTERM from PID 1 (systemd). machine # [ 5.017403] NET: Registered PF_VSOCK protocol family machine # [ 5.398799] systemd[1]: systemd 262-175b52e running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) machine # [ 5.408638] systemd[1]: Detected virtualization kvm. machine # [ 5.410558] systemd[1]: Detected architecture x86-64. machine # [ 5.412531] systemd[1]: Detected first boot. machine # [ 5.419791] systemd[1]: Initializing machine ID from random generator. machine # [ 5.540611] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 5.643721] systemd[1]: Applying preset policy. machine # [ 6.000888] systemd[1]: Populated /etc with preset unit settings. machine # [ 6.404519] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 6.406001] systemd[1]: Stopped initrd-switch-root.service. machine # [ 6.408492] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 6.410683] systemd[1]: Created slice Slice /system/getty. machine # [ 6.412005] systemd[1]: Created slice User and Session Slice. machine # [ 6.413147] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 6.414334] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 6.415557] systemd[1]: Expecting device /dev/hvc0... machine # [ 6.416297] systemd[1]: Expecting device /dev/ttyS0... machine # [ 6.417000] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 6.417833] systemd[1]: Stopped target initrd-fs.target. machine # [ 6.418589] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 6.419393] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 6.420222] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 6.421129] systemd[1]: Reached target Path Units. machine # [ 6.421806] systemd[1]: Reached target Remote File Systems. machine # [ 6.422610] systemd[1]: Reached target Slice Units. machine # [ 6.423342] systemd[1]: Reached target Swaps. machine # [ 6.437381] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 6.440786] systemd[1]: Listening on Process Core Dump Socket. machine # [ 6.443170] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 6.445468] systemd[1]: Listening on Factory Reset Management. machine # [ 6.446381] systemd[1]: Listening on Hostname Service Socket. machine # [ 6.449597] systemd[1]: Starting Journal Log Access Socket... machine # [ 6.451480] systemd[1]: Listening on Journal Audit Socket. machine # [ 6.454015] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 6.455208] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 6.456866] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.458144] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 6.466482] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 6.467626] systemd[1]: Listening on udev Varlink Socket. machine # [ 6.471631] systemd[1]: Mounting Huge Pages File System... machine # [ 6.474749] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 6.480001] systemd[1]: Mounting Kernel Debug File System... machine # [ 6.486648] systemd[1]: Mounting Kernel Trace File System... machine # [ 6.493422] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 6.495317] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio machine # [ 6.501960] systemd[1]: Starting Load Kernel Module configfs... machine # [ 6.506634] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 6.512065] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 6.514318] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 6.520751] systemd[1]: Mounting FUSE Control File System... machine # [ 6.522354] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 6.563359] systemd[1]: Starting Journal Service... machine # [ 6.576727] systemd[1]: Starting Load Kernel Modules... machine # [ 6.600948] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 6.615507] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 6.618533] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.633907] systemd[1]: Starting Coldplug All udev Devices... machine # [ 6.655558] systemd[1]: Listening on Journal Log Access Socket. machine # [ 6.660427] systemd[1]: Mounted Huge Pages File System. machine # [ 6.664480] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 6.668659] systemd[1]: Mounted Kernel Debug File System. machine # [ 6.674055] systemd-journald[286]: Collecting audit messages is enabled. machine # [ 6.676038] systemd[1]: Mounted Kernel Trace File System. machine # [ 6.683414] loop: module loaded machine # [ 6.684290] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 6.689142] EXT4-fs (vda): re-mounted 34a6b496-c3f9-4684-ae1e-e673c615f026. machine # [ 6.690780] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 6.696367] systemd[1]: Finished Load Kernel Module configfs. machine # [ 6.702788] systemd[1]: Mounted FUSE Control File System. machine # [ 6.559487] systemd[1]: Queued start job for default target Multi-User System. machine # [ 6.708161] systemd[1]: Started Journal Service. machine # [ 6.563296] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 6.565263] systemd-modules-load[287]: Inserted module 'loop' machine # [ 6.567164] systemd[1]: Finished Load Kernel Modules. machine # [ 6.571131] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 6.588082] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 6.595395] systemd[1]: Mounting Kernel Configuration File System... machine # [ 6.601583] systemd[1]: Starting Firewall... machine # [ 6.613545] systemd-oomd[288]: No swap; memory pressure usage will be degraded machine # [ 6.618507] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 6.619945] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 6.629710] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 6.642865] systemd[1]: Starting Apply Kernel Variables... machine # [ 6.657869] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 6.659317] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.660562] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 6.847813] systemd-journald[286]: Received client request to flush runtime journal. machine # [ 6.827356] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 6.828347] systemd[1]: Mounted Kernel Configuration File System. machine # [ 6.831064] systemd[1]: Reached target First Boot Complete. machine # [ 6.832435] systemd[1]: Finished Apply Kernel Variables. machine # [ 6.834705] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 6.835721] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 6.839322] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 6.889460] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 6.891502] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 6.895642] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 6.976282] systemd[1]: Finished Coldplug All udev Devices. machine # [ 6.978448] systemd-udevd[325]: Using default interface naming scheme 'v261'. machine # [ 7.075312] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 7.193963] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 7.263399] systemd[1]: Mounting /run/wrappers... machine # [ 7.275212] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 7.304778] systemd[1]: Mounted /run/wrappers. machine # [ 7.307268] systemd[1]: Reached target Local File Systems. machine # [ 7.309760] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 7.316367] systemd[1]: Starting register-nix-paths.service... machine # [ 7.319582] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 7.321132] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 7.330301] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 7.351517] systemd[1]: Starting Create System Files and Directories... machine # [ 7.354302] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped. machine # [ 7.382529] (udev-worker)[360]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 7.401097] (udev-worker)[359]: Network interface NamePolicy= disabled on kernel command line. machine # [ 7.402479] (udev-worker)[360]: Network interface NamePolicy= disabled on kernel command line. machine # [ 7.433964] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 7.439388] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 7.495331] systemd[1]: Finished Create System Files and Directories. machine # [ 7.507444] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 7.514176] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 7.595281] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 7.644934] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 7.653093] systemd[1]: Starting Update is Completed... machine # [ 7.668862] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 7.670104] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 7.672369] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 7.674801] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 7.678266] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 7.679900] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.682439] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.733486] systemd[1]: Finished Update is Completed. machine # [ 8.061258] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 machine # [ 8.066762] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console machine # [ 7.931086] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 7.934366] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 8.098008] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:09.0/virtio7/input/input4 machine # [ 7.980143] systemd[1]: Finished Firewall. machine # [ 8.121960] ACPI: button: Power Button [PWRF] machine # [ 8.145027] mousedev: PS/2 mouse device common for all mice machine # [ 8.183000] rtc_cmos 00:04: RTC can wake from S4 machine # [ 8.203602] rtc_cmos 00:04: registered as rtc0 machine # [ 8.203670] rtc_cmos 00:04: setting system clock to 2026-09-04T02:38:46 UTC (1788489526) machine # [ 8.203747] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs machine # [ 8.205600] systemd-journald[286]: Time jumped backwards, rotating. machine # [ 8.252211] Console: switching to colour dummy device 80x25 machine # [ 8.291514] parport_pc 00:02: reported by Plug and Play ACPI machine # [ 8.291641] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] machine # [ 8.306645] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized machine # [ 8.255655] systemd[1]: Finished register-nix-paths.service. machine # [ 8.257858] systemd[1]: Reached target System Initialization. machine # [ 8.262231] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 8.263533] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 8.264754] systemd[1]: Reached target Timer Units. machine # [ 8.265969] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 8.416251] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt machine # [ 8.417156] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD machine # [ 8.271428] systemd[1]: Listening on Nix Daemon Socket. machine # [ 8.272761] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 8.275305] systemd[1]: Reached target Socket Units. machine # [ 8.277190] systemd[1]: Reached target Basic System. machine # [ 8.425823] [drm] Found bochs VGA, ID 0xb0c5. machine # [ 8.426483] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. machine # [ 8.280950] systemd[1]: Started backdoor.service. machine # [ 8.285783] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 8.297457] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 8.309824] systemd[1]: Starting Post-Boot Actions... machine # [ 8.469125] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic machine # [ 8.324838] systemd[1]: Started Reset console on configuration changes. machine # [ 8.341794] systemd[1]: Starting resolvconf update... machine # [ 8.510527] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0 machine # connecting to host... machine # [ 8.404852] systemd[1]: Starting D-Bus System Message Bus... machine # [ 8.423243] nsncd[502]: Sep 04 02:38:46.862 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 8.425601] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 8.433149] systemd[1]: Finished Post-Boot Actions. machine: Guest shell says: b'Spawning backdoor root shell...\n' machine # [ 8.451149] systemd[1]: Reached target Host and Network Name Lookups. machine: connected to guest root shell machine # [ 8.454061] systemd[1]: Reached target User and Group Name Lookups. machine: (connecting took 9.12 seconds) machine: (finished: waiting for the VM to finish booting, in 9.33 seconds) machine # [ 8.616357] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6 machine # [ 8.471673] systemd[1]: Starting User Login Management... machine # [ 8.620664] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5 machine # [ 8.480428] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 8.621781] dbus-broker-launch[512]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 8.849899] Console: switching to colour frame buffer device 160x50 machine # [ 8.922118] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device machine # [ 8.668852] dbus-broker-launch[512]: NSS returned no entry for 'systemd-timesync' machine # [ 8.779119] dbus-broker-launch[512]: Invalid user-name in /nix/store/nxbpk401an3y90294yk3vj386qpkvmng-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 8.781929] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 8.786077] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 8.790832] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 8.792677] systemd[1]: Stopping User and Group Name Lookups... machine # [ 8.796177] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 8.797756] systemd[1]: Starting Virtual Console Setup... machine # [ 8.800473] systemd[1]: nscd.service: Deactivated successfully. machine # [ 8.802443] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 8.805083] systemd[1]: Started D-Bus System Message Bus. machine # [ 8.806911] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 8.808785] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 8.814963] dbus-broker-launch[512]: Ready machine # [ 8.815981] systemd[1]: Stopped Virtual Console Setup. machine # [ 8.817200] systemd-logind[528]: New seat seat0. machine # [ 8.819451] systemd[1]: Starting Virtual Console Setup... machine # [ 8.821210] systemd[1]: Started User Login Management. machine # [ 8.823099] systemd-logind[528]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) machine # [ 8.827093] systemd-logind[528]: Watching system buttons on /dev/input/event2 (Power Button) machine # [ 8.843911] systemd[1]: Starting linger-users.service... machine # [ 8.858728] systemd-logind[528]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 8.860762] nsncd[587]: Sep 04 02:38:47.301 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 8.865944] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 8.869571] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 8.870901] systemd[1]: Reached target User and Group Name Lookups. machine # [ 8.895161] systemd[1]: Finished resolvconf update. machine # [ 8.898255] systemd[1]: Reached target Preparation for Network. machine # [ 8.908595] systemd[1]: Starting DHCP Client... machine # [ 8.918725] systemd[1]: Starting Address configuration of eth1... machine # [ 8.928499] systemd[1]: Starting Extra networking commands.... machine # [ 9.136640] ppdev: user-space parallel port driver machine # [ 9.005657] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 9.007915] systemd[1]: Finished linger-users.service. machine # [ 9.029312] systemd[1]: Created slice Slice /user/1001. machine # [ 9.036112] systemd[1]: Starting User Runtime Directory /run/user/1001... machine # [ 9.185339] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660) machine # [ 9.224589] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0) machine # [ 9.115235] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 9.116303] systemd[1]: Stopped Virtual Console Setup. machine # [ 9.123228] systemd[1]: Starting Virtual Console Setup... machine # [ 9.168821] systemd[1]: Finished User Runtime Directory /run/user/1001. machine # [ 9.173187] systemd[1]: Starting User Manager for UID 1001... machine # [ 9.191943] network-addresses-eth1-start[617]: adding address 192.168.1.1/24... done machine # [ 9.230189] network-addresses-eth1-start[617]: adding address 2001:db8:1::1/64... done machine # [ 9.276198] systemd[1]: Finished Address configuration of eth1. machine # [ 9.279906] (systemd)[636]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 9.308358] systemd-logind[528]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'. machine # [ 9.367327] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 9.576292] kvm_amd: TSC scaling supported machine # [ 9.576907] kvm_amd: Nested Virtualization enabled machine # [ 9.580383] kvm_amd: Nested Paging enabled machine # [ 9.582130] kvm_amd: LBR virtualization supported machine # [ 9.582957] kvm_amd: Virtual VMLOAD VMSAVE supported machine # [ 9.585129] kvm_amd: Virtual GIF supported machine # [ 9.585863] kvm_amd: Virtual NMI enabled machine # [ 9.462345] systemd[1]: Finished Extra networking commands.. machine # [ 9.463756] systemd[1]: Reached target Network. machine # [ 9.468303] systemd[1]: Starting Permit User Sessions... machine # [ 9.492682] dhcpcd[641]: dhcpcd-10.3.2 starting machine # [ 9.508358] dhcpcd[700]: dev: loaded udev machine # [ 9.669400] EDAC MC: Ver: 3.0.0 machine # [ 9.537581] systemd[1]: Finished Permit User Sessions. machine # [ 9.542704] systemd[1]: Started Getty on tty1. machine # [ 9.546148] systemd[1]: Reached target Login Prompts. machine # [ 9.698866] 8021q: 802.1Q VLAN Support v1.8 machine # [ 9.701943] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 9.596118] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 9.816029] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 9.704657] systemd[636]: Queued start job for default target Main User Target. machine # [ 9.707833] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 9.858788] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 9.859989] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 9.717591] systemd[636]: Created slice User Application Slice. machine # [ 9.719160] systemd[636]: Started Daily Cleanup of User's Temporary Directories. machine # [ 9.867555] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 9.868758] cfg80211: failed to load regulatory.db machine # [ 9.723413] systemd[636]: Reached target Paths. machine # [ 9.724966] systemd[636]: Reached target Timers. machine # [ 9.730630] systemd[636]: Starting D-Bus User Message Bus Socket... machine # [ 9.735704] systemd[636]: Starting Create User Files and Directories... machine # [ 9.787454] systemd[636]: Finished Create User Files and Directories. machine # [ 9.968788] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 9.826667] dhcpcd[700]: eth0: waiting for carrier machine # [ 9.828672] dhcpcd[700]: eth0: carrier acquired machine # [ 9.833813] systemd[636]: Listening on D-Bus User Message Bus Socket. machine # [ 9.838891] systemd[636]: Reached target Sockets. machine # [ 9.841155] systemd[636]: Reached target Basic System. machine # [ 9.843282] systemd[1]: Started User Manager for UID 1001. machine # [ 9.846233] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 9.860318] dhcpcd[700]: DUID 00:01:00:01:32:2c:eb:b8:52:54:00:12:34:56 machine # [ 9.862069] dhcpcd[700]: eth0: IAID 00:12:34:56 machine # [ 9.863431] dhcpcd[700]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 9.894660] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 9.896457] systemd[636]: Reached target Main User Target. machine # [ 9.898323] systemd[636]: Startup finished in 549ms. machine # [ 9.983992] systemd-vconsole-setup[632]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 9.989304] systemd[1]: Finished Virtual Console Setup. machine # [ 10.636446] dhcpcd[700]: eth0: soliciting a DHCP lease machine # [ 10.806869] NET: Registered PF_PACKET protocol family machine # [ 10.666308] dhcpcd[700]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 10.669358] dhcpcd[700]: eth0: probing address 10.0.2.15/24 machine # [ 12.176610] dhcpcd[700]: eth0: soliciting an IPv6 router machine # [ 12.179578] dhcpcd[700]: eth0: Router Advertisement from fe80::2 machine # [ 12.181733] dhcpcd[700]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 12.183989] dhcpcd[700]: eth0: adding route to fec0::/64 machine # [ 12.185930] dhcpcd[700]: eth0: adding default route via fe80::2 machine # [ 16.477805] dhcpcd[700]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 16.481385] dhcpcd[700]: eth0: adding route to 10.0.2.0/24 machine # [ 16.483414] dhcpcd[700]: eth0: adding default route via 10.0.2.2 machine # [ 16.597517] systemd[1]: Started DHCP Client. machine # [ 16.599478] systemd[1]: Reached target Multi-User System. machine # [ 16.600432] systemd[1]: Startup finished in 847ms (kernel) + 3.963s (initrd) + 11.788s (userspace) = 16.599s. machine: (finished: waiting for unit multi-user.target, in 18.25 seconds) machine: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test machine # Checking switch inhibitors... done machine # [ 17.863938] nixos[858]: switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test machine # [ 17.866177] systemd[1]: Stopped target Remote File Systems. machine # [ 17.867801] systemd[1]: Stopped target Local File Systems. machine # activating the configuration... machine # setting up /etc... machine # [ 18.210477] systemd[1]: Reload requested from client PID 858 ('.switch-to-conf') (unit backdoor.service)... machine # [ 18.212187] systemd[1]: Reloading... machine # [ 18.680272] systemd[1]: Reloading finished in 465 ms. machine # reloading user units for usertest... machine # [ 18.712901] systemd[636]: Created slice User Core Session Slice. machine # [ 18.715982] systemd[636]: Starting D-Bus User Message Bus... machine # [ 18.765479] dbus-broker-launch[928]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/nxbpk401an3y90294yk3vj386qpkvmng-system-path/share/dbus-1/services/org.freedesktop.import1.service' machine # [ 18.768106] dbus-broker-launch[928]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/nxbpk401an3y90294yk3vj386qpkvmng-system-path/share/dbus-1/services/org.freedesktop.machine1.service' machine # [ 18.770874] dbus-broker-launch[928]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/nxbpk401an3y90294yk3vj386qpkvmng-system-path/share/dbus-1/services/org.freedesktop.portable1.service' machine # [ 18.773851] dbus-broker-launch[928]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/nxbpk401an3y90294yk3vj386qpkvmng-system-path/share/dbus-1/services/org.freedesktop.systemd1.service' machine # [ 18.777036] dbus-broker-launch[928]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored machine # [ 18.778587] dbus-broker-launch[928]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored machine # [ 18.780224] systemd[636]: Started D-Bus User Message Bus. machine # [ 18.790118] dbus-broker-launch[928]: Ready machine # [ 18.871746] systemd[636]: Reexecution requested from client PID 927 ('switch-to-confi')... machine # [ 18.873734] systemd[636]: Reexecuting. machine # [ 18.903192] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 19.042392] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 19.047752] systemd[636]: Reload requested from client PID 927 ('switch-to-confi')... machine # [ 19.048941] systemd[636]: Reloading... machine # [ 19.185974] systemd[636]: Reloading finished in 135 ms. machine # [ 19.191097] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 19.199400] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 19.200424] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 19.203603] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 19.240751] systemd[636]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 19.252780] systemd[1]: Starting Re-setup tmpfiles on a system that is already running.... machine # [ 19.373643] systemd[1]: Finished Re-setup tmpfiles on a system that is already running.. machine # [ 19.374825] systemd[1]: Reached target Reactivate sysinit units. machine # [ 19.379103] systemd[1]: Reached target Local File Systems. machine # [ 19.380309] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 19.385075] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 19.386056] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 19.387667] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 19.392393] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 19.394455] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 19.397350] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 19.398492] systemd[1]: Reached target Remote File Systems. machine # [ 19.406901] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 19.408662] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 19.693865] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 19.696210] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 19.700449] systemd[1]: Starting linger-users.service... machine # [ 19.751060] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 19.753549] systemd[1]: Finished linger-users.service. machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service machine # [ 20.008978] nixos[858]: finished switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test, in 2.63 seconds) machine: must succeed: rm /etc/NIXOS machine: (finished: must succeed: rm /etc/NIXOS, in 0.02 seconds) machine: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test/bin/switch-to-configuration test machine # this should succeed (config: /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test, action: test) machine # Checking switch inhibitors... done machine # [ 20.617225] nixos[1006]: switching to system configuration /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test machine # [ 20.620316] systemd[1]: Stopped target Local File Systems. machine # [ 20.622094] systemd[1]: Stopped target Remote File Systems. machine # activating the configuration... machine # removing user ‘usertest’ machine # setting up /etc... machine # [ 21.032070] systemd[1]: Reload requested from client PID 1006 ('.switch-to-conf') (unit backdoor.service)... machine # [ 21.033942] systemd[1]: Reloading... machine # [ 21.504409] systemd[1]: Reloading finished in 468 ms. machine # reloading user units for usertest... machine # [ 21.620476] systemd[636]: Reexecution requested from client PID 1077 ('switch-to-confi')... machine # [ 21.621799] systemd[636]: Reexecuting. machine # [ 21.647785] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 21.781526] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 21.788063] systemd[636]: Reload requested from client PID 1077 ('switch-to-confi')... machine # [ 21.789444] systemd[636]: Reloading... machine # [ 21.912099] systemd[636]: Reloading finished in 121 ms. machine # [ 21.917855] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 21.925305] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 21.926156] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 21.928905] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 21.960410] systemd[636]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 21.964563] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 21.965703] systemd[1]: Stopping Reactivate sysinit units... machine # reloading the following units: dbus-broker.service[ 21.967323] systemd[1]: Reached target Reactivate sysinit units. machine # machine # [ 21.969658] dbus-broker-launch[512]: Caught SIGHUP, trigger reload. machine # [ 21.971190] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 21.987174] dbus-broker-launch[512]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 21.989190] dbus-broker-launch[512]: NSS returned no entry for 'systemd-timesync' machine # [ 21.990189] dbus-broker-launch[512]: Invalid user-name in /nix/store/0n1xh0s1wqya4gyrk665anqq5xkl5bll-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 22.009823] systemd[1]: Reloaded D-Bus System Message Bus. machine # restarting the following units: network-addresses-eth1.service machine # [ 22.015456] systemd[1]: Stopping Address configuration of eth1... machine # [ 22.050448] network-addresses-eth1-pre-stop[1089]: deleting address 192.168.1.1/24... done machine # [ 22.059196] network-addresses-eth1-pre-stop[1089]: deleting address 2001:db8:1::1/64... done machine # [ 22.066619] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 22.068491] systemd[1]: Stopped Address configuration of eth1. machine # [ 22.072068] systemd[1]: Starting Address configuration of eth1... machine # [ 22.122469] network-addresses-eth1-start[1094]: adding address 192.168.1.2/24... done machine # [ 22.132230] network-addresses-eth1-start[1094]: adding address 2001:db8:1::2/64... done machine # [ 22.143660] systemd[1]: Finished Address configuration of eth1. machine # [ 22.147345] systemd[1]: Reached target Remote File Systems. machine # [ 22.152166] systemd[1]: Starting linger-users.service... machine # [ 22.154837] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.157513] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 22.160636] systemd[1]: Reached target Local File Systems. machine # [ 22.165623] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 22.169425] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 22.171093] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 22.172420] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 22.174383] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 22.175834] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.177965] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.214568] linger-users-start[1108]: id: ‘usertest’: no such user machine # [ 22.216187] linger-users-start[1105]: Removing linger for missing user usertest machine # [ 22.223710] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 22.225423] systemd[1]: Finished linger-users.service. machine # [ 22.492800] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 22.494496] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 22.750726] nixos[1006]: finished switching to system configuration /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test machine: (finished: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test/bin/switch-to-configuration test, in 2.72 seconds) machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 23.245319] nixos[1173]: switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test machine # [ 23.248594] systemd[1]: Stopped target Remote File Systems. machine # [ 23.249971] systemd[1]: Stopped target Local File Systems. machine # [ 23.632644] systemd[1]: Reload requested from client PID 1173 ('.switch-to-conf') (unit backdoor.service)... machine # [ 23.634324] systemd[1]: Reloading... machine # [ 24.135062] systemd[1]: Reloading finished in 498 ms. machine # [ 24.228141] systemd[636]: Reexecution requested from client PID 1242 ('switch-to-confi')... machine # [ 24.229986] systemd[636]: Reexecuting. machine # [ 24.256446] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 24.388120] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 24.394247] systemd[636]: Reload requested from client PID 1242 ('switch-to-confi')... machine # [ 24.395509] systemd[636]: Reloading... machine # [ 24.533369] systemd[636]: Reloading finished in 135 ms. machine # [ 24.538877] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 24.545789] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 24.546826] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 24.549825] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 24.577702] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 24.581639] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 24.582771] systemd[1]: Stopping Reactivate sysinit units... machine # [ 24.584148] systemd[1]: Reached target Reactivate sysinit units. machine # [ 24.586278] dbus-broker-launch[512]: Caught SIGHUP, trigger reload. machine # [ 24.587560] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 24.595171] dbus-broker-launch[512]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 24.597050] dbus-broker-launch[512]: NSS returned no entry for 'systemd-timesync' machine # [ 24.597962] dbus-broker-launch[512]: Invalid user-name in /nix/store/nxbpk401an3y90294yk3vj386qpkvmng-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 24.611764] systemd[1]: Reloaded D-Bus System Message Bus. machine # [ 24.616899] systemd[1]: Stopping Address configuration of eth1... machine # [ 24.647761] network-addresses-eth1-pre-stop[1254]: deleting address 192.168.1.2/24... done machine # [ 24.656636] network-addresses-eth1-pre-stop[1254]: deleting address 2001:db8:1::2/64... done machine # [ 24.663499] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 24.665489] systemd[1]: Stopped Address configuration of eth1. machine # [ 24.669522] systemd[1]: Starting Address configuration of eth1... machine # [ 24.730773] network-addresses-eth1-start[1259]: adding address 192.168.1.1/24... done machine # [ 24.740584] network-addresses-eth1-start[1259]: adding address 2001:db8:1::1/64... done machine # [ 24.750807] systemd[1]: Finished Address configuration of eth1. machine # [ 24.753527] systemd[1]: Reached target Local File Systems. machine # [ 24.755843] systemd[1]: Reached target Remote File Systems. machine # [ 24.759402] systemd[1]: Starting linger-users.service... machine # [ 24.770113] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.772105] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 24.775716] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 24.780914] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 24.782120] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 24.783993] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 24.787223] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 24.790093] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.791297] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.819171] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 24.820821] systemd[1]: Finished linger-users.service. machine # [ 25.041366] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 25.043084] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 25.298271] nixos[1173]: finished switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.55 seconds) subtest: user services machine: waiting for unit user@1001.service machine: (finished: waiting for unit user@1001.service, in 0.13 seconds) machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 25.929053] nixos[1332]: switching to system configuration /nix/store/apczps80kc9hdhrs5i9r3inl5qbj044w-nixos-system-machine-test machine # [ 25.933233] systemd[1]: Stopped target Remote File Systems. machine # [ 25.936112] systemd[1]: Stopped target Local File Systems. machine # [ 26.418947] systemd[1]: Reload requested from client PID 1332 ('.switch-to-conf') (unit backdoor.service)... machine # [ 26.420823] systemd[1]: Reloading... machine # [ 26.852459] systemd[1]: Reloading finished in 429 ms. machine # [ 26.981291] systemd[636]: Reexecution requested from client PID 1402 ('switch-to-confi')... machine # [ 26.982689] systemd[636]: Reexecuting. machine # [ 27.009930] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.191465] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.195740] systemd[636]: Reload requested from client PID 1402 ('switch-to-confi')... machine # [ 27.197273] systemd[636]: Reloading... machine # [ 27.342722] systemd[636]: Reloading finished in 143 ms. machine # [ 27.347929] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.356363] systemd[636]: Starting usertest.service... machine # [ 27.386617] systemd[636]: Finished usertest.service. machine # [ 27.389347] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 27.391116] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 27.393636] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 27.432641] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 27.437285] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 27.438212] systemd[1]: Stopping Reactivate sysinit units... machine # [ 27.438937] systemd[1]: Reached target Reactivate sysinit units. machine # [ 27.441796] systemd[1]: Reached target Local File Systems. machine # [ 27.444067] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 27.448573] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 27.449406] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 27.450576] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 27.453932] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 27.456851] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.458871] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.463602] systemd[1]: Reached target Remote File Systems. machine # [ 27.472059] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.473617] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 27.796466] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 27.798516] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 27.803411] systemd[1]: Starting linger-users.service... machine # [ 27.861656] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 27.864362] systemd[1]: Finished linger-users.service. machine # [ 28.122543] nixos[1332]: finished switching to system configuration /nix/store/apczps80kc9hdhrs5i9r3inl5qbj044w-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.70 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 28.231163] sudo[1472]: 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.247470] sudo[1472]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 28.262422] sudo[1472]: 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.13 seconds) machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 29.105373] nixos[1481]: switching to system configuration /nix/store/apczps80kc9hdhrs5i9r3inl5qbj044w-nixos-system-machine-test machine # [ 29.110428] systemd[1]: Stopped target Remote File Systems. machine # [ 29.111871] systemd[1]: Stopped target Local File Systems. machine # [ 29.641737] systemd[1]: Reload requested from client PID 1481 ('.switch-to-conf') (unit backdoor.service)... machine # [ 29.644084] systemd[1]: Reloading... machine # [ 30.145400] systemd[1]: Reloading finished in 498 ms. machine # [ 30.269243] systemd[636]: Reexecution requested from client PID 1551 ('switch-to-confi')... machine # [ 30.271229] systemd[636]: Reexecuting. machine # [ 30.301593] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 30.459513] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 30.465182] systemd[636]: Reload requested from client PID 1551 ('switch-to-confi')... machine # [ 30.467280] systemd[636]: Reloading... machine # [ 30.606488] systemd[636]: Reloading finished in 137 ms. machine # [ 30.611865] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 30.618769] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 30.619796] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 30.623116] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 30.656680] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 30.660184] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 30.661867] systemd[1]: Stopping Reactivate sysinit units... machine # [ 30.662808] systemd[1]: Reached target Reactivate sysinit units. machine # [ 30.664710] systemd[1]: Reached target Local File Systems. machine # [ 30.671121] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 30.674808] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 30.675662] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 30.677393] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 30.680199] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 30.682486] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 30.684564] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 30.688442] systemd[1]: Reached target Remote File Systems. machine # [ 30.694260] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 30.695838] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 30.943727] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 30.945164] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 30.952225] systemd[1]: Starting linger-users.service... machine # [ 31.056169] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 31.057723] systemd[1]: Finished linger-users.service. machine # [ 31.314486] nixos[1481]: finished switching to system configuration /nix/store/apczps80kc9hdhrs5i9r3inl5qbj044w-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 3.05 seconds) machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1 machine # [ 31.801712] nixos[1622]: switching to system configuration /nix/store/23jp4pz6198dxbfzqii2qwyx1a4sba12-nixos-system-machine-test machine # [ 31.804422] systemd[1]: Stopped target Local File Systems. machine # [ 31.806318] systemd[1]: Stopped target Remote File Systems. machine # [ 32.274905] systemd[1]: Reload requested from client PID 1622 ('.switch-to-conf') (unit backdoor.service)... machine # [ 32.277106] systemd[1]: Reloading... machine # [ 32.755261] systemd[1]: Reloading finished in 476 ms. machine # [ 32.935528] systemd[636]: Stopped usertest.service. machine # [ 32.937064] systemd[636]: Reexecution requested from client PID 1692 ('switch-to-confi')... machine # [ 32.939681] systemd[636]: Reexecuting. machine # [ 32.965066] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.092199] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.098219] systemd[636]: Reload requested from client PID 1692 ('switch-to-confi')... machine # [ 33.099664] systemd[636]: Reloading... machine # [ 33.225616] systemd[636]: Reloading finished in 123 ms. machine # [ 33.230921] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.238944] systemd[636]: Starting usertest.service... machine # [ 33.281215] systemd[636]: Finished usertest.service. machine # [ 33.284067] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 33.285200] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 33.290744] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 33.342351] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 33.346768] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 33.349179] systemd[1]: Stopping Reactivate sysinit units... machine # [ 33.350308] systemd[1]: Reached target Reactivate sysinit units. machine # [ 33.356067] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.357971] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 33.363152] systemd[1]: Reached target Remote File Systems. machine # [ 33.368864] systemd[1]: Starting linger-users.service... machine # [ 33.370544] systemd[1]: Reached target Local File Systems. machine # [ 33.378077] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 33.384487] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 33.385665] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 33.387533] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 33.390807] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 33.393517] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.395223] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.441902] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 33.443600] systemd[1]: Finished linger-users.service. machine # [ 33.683482] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 33.686980] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 33.941456] nixos[1622]: finished switching to system configuration /nix/store/23jp4pz6198dxbfzqii2qwyx1a4sba12-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 2.62 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 33.977850] sudo[1762]: 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.989366] sudo[1762]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 33.999191] sudo[1762]: 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/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 34.473807] nixos[1771]: switching to system configuration /nix/store/d9j7pw22w68mmylrhq0iwj3k1wa7f51l-nixos-system-machine-test machine # [ 34.476246] systemd[1]: Stopped target Local File Systems. machine # [ 34.478327] systemd[1]: Stopped target Remote File Systems. machine # [ 34.924849] systemd[1]: Reload requested from client PID 1771 ('.switch-to-conf') (unit backdoor.service)... machine # [ 34.926567] systemd[1]: Reloading... machine # [ 35.444998] systemd[1]: Reloading finished in 515 ms. machine # [ 35.583598] systemd[636]: Reexecution requested from client PID 1840 ('switch-to-confi')... machine # [ 35.585154] systemd[636]: Reexecuting. machine # [ 35.612930] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.774618] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.781639] systemd[636]: Reload requested from client PID 1840 ('switch-to-confi')... machine # [ 35.783339] systemd[636]: Reloading... machine # [ 35.931263] systemd[636]: Reloading finished in 145 ms. machine # [ 35.936099] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.941137] systemd[636]: Stopped usertest.service. machine # [ 35.941994] systemd[636]: Stopping usertest.service... machine # [ 35.945186] systemd[636]: Starting usertest.service... machine # [ 35.973216] systemd[636]: Finished usertest.service. machine # [ 35.980088] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 35.981028] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 35.983701] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 36.013862] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 36.017290] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 36.018905] systemd[1]: Stopping Reactivate sysinit units... machine # [ 36.020362] systemd[1]: Reached target Reactivate sysinit units. machine # [ 36.021428] systemd[1]: Reached target Remote File Systems. machine # [ 36.023390] systemd[1]: Reached target Local File Systems. machine # [ 36.027218] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 36.031889] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 36.033115] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 36.034292] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 36.036202] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 36.037611] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.038762] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.050098] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.051592] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 36.373719] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 36.375342] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 36.380114] systemd[1]: Starting linger-users.service... machine # [ 36.424873] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 36.426390] systemd[1]: Finished linger-users.service. machine # [ 36.681527] nixos[1771]: finished switching to system configuration /nix/store/d9j7pw22w68mmylrhq0iwj3k1wa7f51l-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1, in 2.68 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 36.721419] sudo[1911]: 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 # [ 36.733901] sudo[1911]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 36.744519] sudo[1911]: 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/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1 machine # [ 37.252111] nixos[1920]: switching to system configuration /nix/store/7bmfpyz7aw9pbw93ny767qaxd38c13v1-nixos-system-machine-test machine # [ 37.255662] systemd[1]: Stopped target Local File Systems. machine # [ 37.256616] systemd[1]: Stopped target Remote File Systems. machine # [ 37.732415] systemd[1]: Reload requested from client PID 1920 ('.switch-to-conf') (unit backdoor.service)... machine # [ 37.734143] systemd[1]: Reloading... machine # [ 38.229464] systemd[1]: Reloading finished in 493 ms. machine # [ 38.354501] systemd[636]: Reexecution requested from client PID 1989 ('switch-to-confi')... machine # [ 38.356480] systemd[636]: Reexecuting. machine # [ 38.382264] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 38.564463] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 38.575341] systemd[636]: Reload requested from client PID 1989 ('switch-to-confi')... machine # [ 38.577450] systemd[636]: Reloading... machine # [ 38.730279] systemd[636]: Reloading finished in 151 ms. machine # [ 38.735841] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 38.741877] systemd[636]: Reloading usertest.service... machine # [ 38.768850] systemd[636]: Reloaded usertest.service. machine # [ 38.773832] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 38.775312] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 38.777805] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 38.814424] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 38.818487] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 38.819707] systemd[1]: Stopping Reactivate sysinit units... machine # [ 38.821148] systemd[1]: Reached target Reactivate sysinit units. machine # [ 38.822663] systemd[1]: Reached target Local File Systems. machine # [ 38.824474] systemd[1]: Reached target Remote File Systems. machine # [ 38.826956] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 38.832195] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 38.833171] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 38.835261] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 38.838694] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 38.840480] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 38.842736] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 38.850377] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 38.851722] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 39.108311] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 39.109676] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 39.114435] systemd[1]: Starting linger-users.service... machine # [ 39.156604] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 39.158254] systemd[1]: Finished linger-users.service. machine # [ 39.412764] nixos[1920]: finished switching to system configuration /nix/store/7bmfpyz7aw9pbw93ny767qaxd38c13v1-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1, in 2.67 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 39.450571] sudo[2060]: 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 # [ 39.461294] sudo[2060]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 39.471359] sudo[2060]: 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/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 39.969703] nixos[2069]: switching to system configuration /nix/store/apczps80kc9hdhrs5i9r3inl5qbj044w-nixos-system-machine-test machine # [ 39.972600] systemd[1]: Stopped target Remote File Systems. machine # [ 39.974140] systemd[1]: Stopped target Local File Systems. machine # [ 40.321589] systemd[1]: Reload requested from client PID 2069 ('.switch-to-conf') (unit backdoor.service)... machine # [ 40.323355] systemd[1]: Reloading... machine # [ 40.778858] systemd[1]: Reloading finished in 453 ms. machine # [ 40.888967] systemd[636]: Stopped usertest.service. machine # [ 40.891772] systemd[636]: Reexecution requested from client PID 2138 ('switch-to-confi')... machine # [ 40.892982] systemd[636]: Reexecuting. machine # [ 40.921356] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 41.042164] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 41.047266] systemd[636]: Reload requested from client PID 2138 ('switch-to-confi')... machine # [ 41.048739] systemd[636]: Reloading... machine # [ 41.165786] systemd[636]: Reloading finished in 115 ms. machine # [ 41.169847] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 41.177243] systemd[636]: Starting usertest.service... machine # [ 41.199169] systemd[636]: Finished usertest.service. machine # [ 41.202104] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 41.202960] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 41.205491] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 41.235771] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 41.239379] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 41.240748] systemd[1]: Stopping Reactivate sysinit units... machine # [ 41.242170] systemd[1]: Reached target Reactivate sysinit units. machine # [ 41.246420] systemd[1]: Reached target Local File Systems. machine # [ 41.248420] systemd[1]: Reached target Remote File Systems. machine # [ 41.249186] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 41.254163] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 41.254937] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 41.256092] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 41.258045] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 41.259476] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 41.260598] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 41.265593] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 41.266807] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 41.521137] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 41.522649] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 41.527353] systemd[1]: Starting linger-users.service... machine # [ 41.568176] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 41.570238] systemd[1]: Finished linger-users.service. machine # [ 41.824097] nixos[2069]: finished switching to system configuration /nix/store/apczps80kc9hdhrs5i9r3inl5qbj044w-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.36 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 41.872936] sudo[2209]: 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 # [ 41.884578] sudo[2209]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 41.894219] sudo[2209]: 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/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1 machine # [ 42.344950] nixos[2218]: switching to system configuration /nix/store/0yh0vsslkc427cwbfgzrlb8hkg2s3la8-nixos-system-machine-test machine # [ 42.347357] systemd[1]: Stopped target Remote File Systems. machine # [ 42.349044] systemd[1]: Stopped target Local File Systems. machine # [ 42.753395] systemd[1]: Reload requested from client PID 2218 ('.switch-to-conf') (unit backdoor.service)... machine # [ 42.754949] systemd[1]: Reloading... machine # [ 43.196552] systemd[1]: Reloading finished in 439 ms. machine # [ 43.304335] systemd[636]: Reexecution requested from client PID 2287 ('switch-to-confi')... machine # [ 43.306645] systemd[636]: Reexecuting. machine # [ 43.332277] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.484884] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.491065] systemd[636]: Reload requested from client PID 2287 ('switch-to-confi')... machine # [ 43.492944] systemd[636]: Reloading... machine # [ 43.630162] systemd[636]: Reloading finished in 135 ms. machine # [ 43.635852] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.641966] systemd[636]: Reloading usertest.service... machine # [ 43.669354] systemd[636]: Reloaded usertest.service. machine # [ 43.675068] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 43.675994] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 43.678638] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 43.719897] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 43.724597] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 43.726193] systemd[1]: Stopping Reactivate sysinit units... machine # [ 43.727570] systemd[1]: Reached target Reactivate sysinit units. machine # [ 43.731901] systemd[1]: Reached target Remote File Systems. machine # [ 43.736452] systemd[1]: Starting linger-users.service... machine # [ 43.747764] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.749644] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 43.754131] systemd[1]: Reached target Local File Systems. machine # [ 43.756159] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 43.762229] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 43.763683] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 43.764893] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 43.766891] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 43.768334] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.769532] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.815666] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 43.817647] systemd[1]: Finished linger-users.service. machine # [ 44.052903] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 44.054607] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 44.312432] nixos[2218]: finished switching to system configuration /nix/store/0yh0vsslkc427cwbfgzrlb8hkg2s3la8-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 2.42 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 44.364623] sudo[2357]: 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 # [ 44.379776] sudo[2357]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 44.387534] sudo[2357]: 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/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1 machine # [ 44.811152] nixos[2366]: switching to system configuration /nix/store/d4721yb2gj1119q7m6vvlbg6yk2vm17q-nixos-system-machine-test machine # [ 44.813545] systemd[1]: Stopped target Remote File Systems. machine # [ 44.815311] systemd[1]: Stopped target Local File Systems. machine # [ 45.202910] systemd[1]: Reload requested from client PID 2366 ('.switch-to-conf') (unit backdoor.service)... machine # [ 45.204711] systemd[1]: Reloading... machine # [ 45.635898] systemd[1]: Reloading finished in 429 ms. machine # [ 45.759382] systemd[636]: Stopped usertest.service. machine # [ 45.762549] systemd[636]: Reexecution requested from client PID 2435 ('switch-to-confi')... machine # [ 45.763902] systemd[636]: Reexecuting. machine # [ 45.789560] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.941584] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.947060] systemd[636]: Reload requested from client PID 2435 ('switch-to-confi')... machine # [ 45.948768] systemd[636]: Reloading... machine # [ 46.075039] systemd[636]: Reloading finished in 124 ms. machine # [ 46.080855] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.087902] systemd[636]: Starting usertest.service... machine # [ 46.110980] systemd[636]: usertest.service: Main process exited, code=exited, status=1/FAILURE machine # [ 46.112330] systemd[636]: usertest.service: Failed with result 'exit-code'. machine # [ 46.114154] systemd[636]: Failed to start usertest.service. machine # [ 46.116868] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 46.117940] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 46.120819] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 46.147581] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 46.151396] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 46.152936] systemd[1]: Stopping Reactivate sysinit units... machine # [ 46.154115] systemd[1]: Reached target Reactivate sysinit units. machine # [ 46.155465] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.157259] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 46.160236] systemd[1]: Reached target Remote File Systems. machine # [ 46.163369] systemd[1]: Starting linger-users.service... machine # [ 46.173414] systemd[1]: Reached target Local File Systems. machine # [ 46.174396] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 46.179412] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 46.180424] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 46.181739] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 46.185681] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 46.187746] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.190853] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.241694] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 46.243952] systemd[1]: Finished linger-users.service. machine # [ 46.501620] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 46.503315] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 46.758162] nixos[2366]: switching to system configuration /nix/store/d4721yb2gj1119q7m6vvlbg6yk2vm17q-nixos-system-machine-test failed (status 4) machine: (finished: must fail: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 2.37 seconds) machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 47.287943] nixos[2508]: switching to system configuration /nix/store/apczps80kc9hdhrs5i9r3inl5qbj044w-nixos-system-machine-test machine # [ 47.290287] systemd[1]: Stopped target Local File Systems. machine # [ 47.292061] systemd[1]: Stopped target Remote File Systems. machine # [ 47.667925] systemd[1]: Reload requested from client PID 2508 ('.switch-to-conf') (unit backdoor.service)... machine # [ 47.669668] systemd[1]: Reloading... machine # [ 48.137760] systemd[1]: Reloading finished in 466 ms. machine # [ 48.274366] systemd[636]: Reexecution requested from client PID 2577 ('switch-to-confi')... machine # [ 48.276454] systemd[636]: Reexecuting. machine # [ 48.306796] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.510458] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.516905] systemd[636]: Reload requested from client PID 2577 ('switch-to-confi')... machine # [ 48.518286] systemd[636]: Reloading... machine # [ 48.690443] systemd[636]: Reloading finished in 169 ms. machine # [ 48.695048] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.705385] systemd[636]: Starting usertest.service... machine # [ 48.739087] systemd[636]: Finished usertest.service. machine # [ 48.742450] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 48.743864] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 48.747314] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 48.791137] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 48.795854] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 48.798364] systemd[1]: Stopping Reactivate sysinit units... machine # [ 48.800531] systemd[1]: Reached target Reactivate sysinit units. machine # [ 48.803216] systemd[1]: Reached target Remote File Systems. machine # [ 48.807819] systemd[1]: Starting linger-users.service... machine # [ 48.814994] systemd[1]: Reached target Local File Systems. machine # [ 48.818955] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 48.825434] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 48.826721] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 48.828614] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 48.832761] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 48.835489] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.838979] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.845742] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.847454] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 48.902121] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 48.904407] systemd[1]: Finished linger-users.service. machine # [ 49.215874] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 49.218138] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 49.473664] nixos[2508]: finished switching to system configuration /nix/store/apczps80kc9hdhrs5i9r3inl5qbj044w-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.72 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 49.524594] sudo[2647]: 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 # [ 49.535480] sudo[2647]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 49.544585] sudo[2647]: 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/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 50.132321] nixos[2656]: switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test machine # [ 50.136883] systemd[1]: Stopped target Local File Systems. machine # [ 50.138632] systemd[1]: Stopped target Remote File Systems. machine # [ 50.658907] systemd[1]: Reload requested from client PID 2656 ('.switch-to-conf') (unit backdoor.service)... machine # [ 50.661389] systemd[1]: Reloading... machine # [ 51.197894] systemd[1]: Reloading finished in 533 ms. machine # [ 51.341047] systemd[636]: Stopped usertest.service. machine # [ 51.342782] systemd[636]: Reexecution requested from client PID 2725 ('switch-to-confi')... machine # [ 51.345630] systemd[636]: Reexecuting. machine # [ 51.372852] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.496063] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.500300] systemd[636]: Reload requested from client PID 2725 ('switch-to-confi')... machine # [ 51.502067] systemd[636]: Reloading... machine # [ 51.666113] systemd[636]: Reloading finished in 162 ms. machine # [ 51.671057] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.678678] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 51.679762] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 51.683095] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 51.729093] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 51.732486] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 51.733980] systemd[1]: Stopping Reactivate sysinit units... machine # [ 51.734955] systemd[1]: Reached target Reactivate sysinit units. machine # [ 51.738909] systemd[1]: Reached target Local File Systems. machine # [ 51.742087] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 51.747277] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 51.748246] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 51.750287] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 51.753134] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 51.755509] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.759052] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.760238] systemd[1]: Reached target Remote File Systems. machine # [ 51.764656] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.767070] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 52.081728] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 52.083895] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 52.090282] systemd[1]: Starting linger-users.service... machine # [ 52.140917] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 52.142548] systemd[1]: Finished linger-users.service. machine # [ 52.397426] nixos[2656]: finished switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.86 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 52.442473] sudo[2793]: 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 # [ 52.453430] sudo[2793]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.465182] sudo[2793]: 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.06 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 52.510186] sudo[2799]: 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.520377] sudo[2799]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.529745] sudo[2799]: 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/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 52.561928] sudo[2805]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 52.571953] sudo[2805]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.582211] sudo[2805]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 52.615619] sudo[2811]: 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.627357] sudo[2811]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.638577] sudo[2811]: 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.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 52.670360] sudo[2817]: 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.680875] sudo[2817]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.689355] systemd[636]: Reload requested from client PID 2822 ('systemctl')... machine # [ 52.691582] systemd[636]: Reloading... machine # [ 52.849146] systemd[636]: Reloading finished in 157 ms. machine # [ 52.853911] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.862370] sudo[2817]: 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.22 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 52.907802] sudo[2827]: 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.918423] sudo[2827]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.932159] systemd[636]: Starting migrated.service... machine # [ 52.964704] systemd[636]: Finished migrated.service. machine # [ 52.968272] 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 start migrated.service, in 0.11 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 53.008320] sudo[2835]: 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 # [ 53.019630] sudo[2835]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 53.030701] sudo[2835]: 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 # [ 53.070535] sudo[2841]: 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.081502] sudo[2841]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 53.092090] sudo[2841]: 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.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 53.126352] sudo[2847]: 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.136417] sudo[2847]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 53.153291] sudo[2847]: 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.06 seconds) machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1 machine # [ 53.646539] nixos[2856]: switching to system configuration /nix/store/igmiy27rz2fpm0yi5rk2i9a3qrnh0als-nixos-system-machine-test machine # [ 53.649237] systemd[1]: Stopped target Local File Systems. machine # [ 53.650947] systemd[1]: Stopped target Remote File Systems. machine # [ 54.094717] systemd[1]: Reload requested from client PID 2856 ('.switch-to-conf') (unit backdoor.service)... machine # [ 54.096674] systemd[1]: Reloading... machine # [ 54.526800] systemd[1]: Reloading finished in 427 ms. machine # [ 54.653351] systemd[636]: Reexecution requested from client PID 2925 ('switch-to-confi')... machine # [ 54.654661] systemd[636]: Reexecuting. machine # [ 54.678894] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.830492] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.837648] systemd[636]: Reload requested from client PID 2925 ('switch-to-confi')... machine # [ 54.839619] systemd[636]: Reloading... machine # [ 54.966616] systemd[636]: Reloading finished in 124 ms. machine # [ 54.970876] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.977358] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 54.979069] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 54.982227] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 55.044839] systemd[636]: Reload requested from client PID 2937 ('systemctl') (unit nixos-activation.service)... machine # [ 55.046648] systemd[636]: Reloading... machine # [ 55.174740] systemd[636]: Reloading finished in 126 ms. machine # [ 55.179084] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.193482] systemd[636]: Stopped migrated.service. machine # [ 55.207229] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 55.209620] systemd[636]: Reload requested from client PID 2925 ('switch-to-confi')... machine # [ 55.211058] systemd[636]: Reloading... machine # [ 55.358147] systemd[636]: Reloading finished in 144 ms. machine # [ 55.362371] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.374774] systemd[636]: Starting migrated.service... machine # [ 55.401636] systemd[636]: Finished migrated.service. machine # [ 55.405483] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 55.407241] systemd[1]: Stopping Reactivate sysinit units... machine # [ 55.407980] systemd[1]: Reached target Reactivate sysinit units. machine # [ 55.410919] systemd[1]: Reached target Remote File Systems. machine # [ 55.414629] systemd[1]: Starting linger-users.service... machine # [ 55.418070] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.420240] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 55.423971] systemd[1]: Reached target Local File Systems. machine # [ 55.431098] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 55.436353] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 55.438551] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 55.440728] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 55.443584] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 55.447200] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.448737] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.515277] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 55.518152] systemd[1]: Finished linger-users.service. machine # [ 55.751529] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 55.753977] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 56.011732] nixos[2856]: finished switching to system configuration /nix/store/igmiy27rz2fpm0yi5rk2i9a3qrnh0als-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 2.87 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 56.065403] sudo[3007]: 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 # [ 56.076552] sudo[3007]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.085472] sudo[3007]: 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 systemctl --user show -p FragmentPath migrated.service machine # [ 56.122160] sudo[3013]: 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 # [ 56.132718] sudo[3013]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.153940] sudo[3013]: 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 # [ 56.192870] sudo[3019]: 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 # [ 56.204584] sudo[3019]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.218217] sudo[3019]: 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.07 seconds) machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 56.670624] nixos[3028]: switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test machine # [ 56.673045] systemd[1]: Stopped target Remote File Systems. machine # [ 56.674780] systemd[1]: Stopped target Local File Systems. machine # [ 57.014520] systemd[1]: Reload requested from client PID 3028 ('.switch-to-conf') (unit backdoor.service)... machine # [ 57.016202] systemd[1]: Reloading... machine # [ 57.496158] systemd[1]: Reloading finished in 477 ms. machine # [ 57.615913] systemd[636]: Stopped migrated.service. machine # [ 57.617266] systemd[636]: Reexecution requested from client PID 3097 ('switch-to-confi')... machine # [ 57.619822] systemd[636]: Reexecuting. machine # [ 57.642298] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.774938] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.781242] systemd[636]: Reload requested from client PID 3097 ('switch-to-confi')... machine # [ 57.783232] systemd[636]: Reloading... machine # [ 57.911472] systemd[636]: Reloading finished in 126 ms. machine # [ 57.915852] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.923068] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 57.923941] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 57.926278] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 57.953475] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 57.956763] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 57.958359] systemd[1]: Stopping Reactivate sysinit units... machine # [ 57.959778] systemd[1]: Reached target Reactivate sysinit units. machine # [ 57.961557] systemd[1]: Reached target Remote File Systems. machine # [ 57.965904] systemd[1]: Starting linger-users.service... machine # [ 57.972525] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 57.974199] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 57.979337] systemd[1]: Reached target Local File Systems. machine # [ 57.980154] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 57.985875] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 57.986792] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 57.988698] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 57.991467] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 57.994158] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 57.997546] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 58.039368] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 58.040902] systemd[1]: Finished linger-users.service. machine # [ 58.244917] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 58.247676] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 58.502534] nixos[3028]: finished switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.29 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 58.550664] sudo[3165]: 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 # [ 58.562526] sudo[3165]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 58.574948] sudo[3165]: 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 # [ 58.623507] sudo[3171]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 58.635041] sudo[3171]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 58.644700] sudo[3171]: 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.07 seconds) machine: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 58.685487] sudo[3177]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 58.695761] sudo[3177]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 58.704466] sudo[3177]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 58.737865] sudo[3183]: 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 # [ 58.747810] sudo[3183]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 58.756418] sudo[3183]: 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.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 58.790890] sudo[3189]: 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 # [ 58.801731] sudo[3189]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 58.810399] systemd[636]: Reload requested from client PID 3194 ('systemctl')... machine # [ 58.813463] systemd[636]: Reloading... machine # [ 58.947940] systemd[636]: Reloading finished in 135 ms. machine # [ 58.953961] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 58.962230] sudo[3189]: 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.21 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 58.999839] sudo[3199]: 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 # [ 59.011219] sudo[3199]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 59.026906] systemd[636]: Starting migrated.service... machine # [ 59.056504] systemd[636]: Finished migrated.service. machine # [ 59.060704] sudo[3199]: 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.10 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 59.092657] sudo[3207]: 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 # [ 59.101860] sudo[3207]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 59.110810] sudo[3207]: 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.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 59.144590] sudo[3213]: 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 # [ 59.154649] sudo[3213]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 59.163506] sudo[3213]: 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.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 59.197426] sudo[3219]: 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 # [ 59.207512] sudo[3219]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 59.223090] sudo[3219]: 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.06 seconds) machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1 machine # [ 59.688303] nixos[3228]: switching to system configuration /nix/store/6zvhh5mp0g7bqkraj9fmm8khqkz3x1lq-nixos-system-machine-test machine # [ 59.691668] systemd[1]: Stopped target Local File Systems. machine # [ 59.692891] systemd[1]: Stopped target Remote File Systems. machine # [ 60.117598] systemd[1]: Reload requested from client PID 3228 ('.switch-to-conf') (unit backdoor.service)... machine # [ 60.119377] systemd[1]: Reloading... machine # [ 60.547951] systemd[1]: Reloading finished in 426 ms. machine # [ 60.678364] systemd[636]: Reexecution requested from client PID 3297 ('switch-to-confi')... machine # [ 60.680909] systemd[636]: Reexecuting. machine # [ 60.708612] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 60.851311] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 60.856816] systemd[636]: Reload requested from client PID 3297 ('switch-to-confi')... machine # [ 60.858529] systemd[636]: Reloading... machine # [ 60.981955] systemd[636]: Reloading finished in 122 ms. machine # [ 60.986870] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 60.994146] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 60.995211] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 60.998725] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 61.034934] systemd[636]: Reload requested from client PID 3309 ('systemctl') (unit nixos-activation.service)... machine # [ 61.036807] systemd[636]: Reloading... machine # [ 61.148145] systemd[636]: Reloading finished in 110 ms. machine # [ 61.154071] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 61.167558] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 61.169865] systemd[636]: Reload requested from client PID 3297 ('switch-to-confi')... machine # [ 61.171692] systemd[636]: Reloading... machine # [ 61.318061] systemd[636]: Reloading finished in 144 ms. machine # [ 61.322918] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 61.331559] systemd[636]: Stopped migrated.service. machine # [ 61.332932] systemd[636]: Stopping migrated.service... machine # [ 61.337100] systemd[636]: Starting migrated.service... machine # [ 61.362112] systemd[636]: Finished migrated.service. machine # [ 61.371114] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 61.373156] systemd[1]: Stopping Reactivate sysinit units... machine # [ 61.375175] systemd[1]: Reached target Reactivate sysinit units. machine # [ 61.380090] systemd[1]: Reached target Remote File Systems. machine # [ 61.385481] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 61.387867] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 61.390446] systemd[1]: Reached target Local File Systems. machine # [ 61.394282] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 61.401292] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 61.403178] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 61.405202] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 61.407782] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 61.409710] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 61.414396] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 61.658164] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 61.659354] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 61.664424] systemd[1]: Starting linger-users.service... machine # [ 61.709837] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 61.711195] systemd[1]: Finished linger-users.service. machine # [ 61.968648] nixos[3228]: finished switching to system configuration /nix/store/6zvhh5mp0g7bqkraj9fmm8khqkz3x1lq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 2.75 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 62.033099] sudo[3378]: 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 # [ 62.048626] sudo[3378]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 62.058267] sudo[3378]: 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.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 62.089711] sudo[3384]: 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 # [ 62.102956] sudo[3384]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 62.123695] sudo[3384]: 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.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 62.163601] sudo[3390]: 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 # [ 62.175725] sudo[3390]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 62.186918] sudo[3390]: 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.06 seconds) machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 62.650733] nixos[3399]: switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test machine # [ 62.653147] systemd[1]: Stopped target Remote File Systems. machine # [ 62.655059] systemd[1]: Stopped target Local File Systems. machine # [ 63.028404] systemd[1]: Reload requested from client PID 3399 ('.switch-to-conf') (unit backdoor.service)... machine # [ 63.030451] systemd[1]: Reloading... machine # [ 64.354116] systemd[1]: Reloading finished in 1320 ms. machine # [ 64.473286] systemd[636]: Stopped migrated.service. machine # [ 64.474467] systemd[636]: Reexecution requested from client PID 3468 ('switch-to-confi')... machine # [ 64.477047] systemd[636]: Reexecuting. machine # [ 64.505339] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 64.640334] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 64.645727] systemd[636]: Reload requested from client PID 3468 ('switch-to-confi')... machine # [ 64.647413] systemd[636]: Reloading... machine # [ 64.780034] systemd[636]: Reloading finished in 130 ms. machine # [ 64.784849] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 64.791277] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 64.793065] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 64.795494] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 64.832388] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 64.836419] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 64.838041] systemd[1]: Stopping Reactivate sysinit units... machine # [ 64.839636] systemd[1]: Reached target Reactivate sysinit units. machine # [ 64.842476] systemd[1]: Reached target Local File Systems. machine # [ 64.845169] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 64.849308] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 64.851631] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 64.853182] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 64.857588] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 64.860155] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 64.862151] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 64.865141] systemd[1]: Reached target Remote File Systems. machine # [ 64.868634] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 64.870546] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 65.125891] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 65.127089] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 65.131369] systemd[1]: Starting linger-users.service... machine # [ 65.174823] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 65.176843] systemd[1]: Finished linger-users.service. machine # [ 65.433942] nixos[3399]: finished switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.25 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 65.496554] sudo[3536]: 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 # [ 65.512119] sudo[3536]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 65.523169] sudo[3536]: 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.08 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 65.561258] sudo[3542]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 65.570443] sudo[3542]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 65.579868] sudo[3542]: 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/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 65.615378] sudo[3548]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 65.625970] sudo[3548]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 65.635852] sudo[3548]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 65.681593] sudo[3554]: 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 # [ 65.694732] sudo[3554]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 65.706156] sudo[3554]: 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.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 65.745597] sudo[3560]: 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 # [ 65.756864] sudo[3560]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 65.765214] systemd[636]: Reload requested from client PID 3565 ('systemctl')... machine # [ 65.767299] systemd[636]: Reloading... machine # [ 65.892882] systemd[636]: Reloading finished in 124 ms. machine # [ 65.896836] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.904415] 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 daemon-reload, in 0.20 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 65.945713] sudo[3570]: 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 # [ 65.957528] sudo[3570]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 65.969553] systemd[636]: Starting migrated.service... machine # [ 65.999269] systemd[636]: Finished migrated.service. machine # [ 66.002537] sudo[3570]: 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.10 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 66.042668] sudo[3578]: 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 # [ 66.055465] sudo[3578]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 66.065056] sudo[3578]: 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 # [ 66.103986] sudo[3584]: 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 # [ 66.114942] sudo[3584]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 66.124202] sudo[3584]: 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.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 66.158750] sudo[3590]: 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 # [ 66.170492] sudo[3590]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 66.187624] sudo[3590]: 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.06 seconds) machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1 machine # [ 66.626958] nixos[3599]: switching to system configuration /nix/store/iqz1rm5y1g94lri4xpdd7arz7bxisbzi-nixos-system-machine-test machine # [ 66.630559] systemd[1]: Stopped target Local File Systems. machine # [ 66.631466] systemd[1]: Stopped target Remote File Systems. machine # [ 67.057771] systemd[1]: Reload requested from client PID 3599 ('.switch-to-conf') (unit backdoor.service)... machine # [ 67.059600] systemd[1]: Reloading... machine # [ 67.729536] systemd[1]: Reloading finished in 667 ms. machine # [ 67.852450] systemd[636]: Reexecution requested from client PID 3668 ('switch-to-confi')... machine # [ 67.855092] systemd[636]: Reexecuting. machine # [ 67.881464] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.024214] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.030158] systemd[636]: Reload requested from client PID 3668 ('switch-to-confi')... machine # [ 68.031689] systemd[636]: Reloading... machine # [ 68.177167] systemd[636]: Reloading finished in 144 ms. machine # [ 68.182106] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.191069] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 68.192036] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 68.194667] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 68.243291] systemd[636]: Reload requested from client PID 3680 ('systemctl') (unit nixos-activation.service)... machine # [ 68.245173] systemd[636]: Reloading... machine # [ 68.377265] systemd[636]: Reloading finished in 130 ms. machine # [ 68.382073] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.395296] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 68.397941] systemd[636]: Reload requested from client PID 3668 ('switch-to-confi')... machine # [ 68.399340] systemd[636]: Reloading... machine # [ 68.536406] systemd[636]: Reloading finished in 135 ms. machine # [ 68.541917] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.550948] systemd[636]: Reloading migrated.service... machine # [ 68.574177] systemd[636]: Reloaded migrated.service. machine # [ 68.580299] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 68.581852] systemd[1]: Stopping Reactivate sysinit units... machine # [ 68.583380] systemd[1]: Reached target Reactivate sysinit units. machine # [ 68.586678] systemd[1]: Reached target Local File Systems. machine # [ 68.587552] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 68.591282] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 68.592943] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 68.594748] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 68.597503] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 68.600813] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 68.603181] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 68.604991] systemd[1]: Reached target Remote File Systems. machine # [ 68.615475] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 68.616990] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 68.880867] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 68.882663] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 68.888662] systemd[1]: Starting linger-users.service... machine # [ 68.940196] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 68.942244] systemd[1]: Finished linger-users.service. machine # [ 69.199067] nixos[3599]: finished switching to system configuration /nix/store/iqz1rm5y1g94lri4xpdd7arz7bxisbzi-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 3.02 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 69.262069] sudo[3749]: 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 # [ 69.279692] sudo[3749]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 69.293394] sudo[3749]: 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.09 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 69.341763] sudo[3755]: 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 # [ 69.357881] sudo[3755]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 69.372098] sudo[3755]: 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.08 seconds) machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 69.927438] nixos[3764]: switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test machine # [ 69.930562] systemd[1]: Stopped target Local File Systems. machine # [ 69.932844] systemd[1]: Stopped target Remote File Systems. machine # [ 70.414821] systemd[1]: Reload requested from client PID 3764 ('.switch-to-conf') (unit backdoor.service)... machine # [ 70.417671] systemd[1]: Reloading... machine # [ 71.042416] systemd[1]: Reloading finished in 621 ms. machine # [ 71.182542] systemd[636]: Stopped migrated.service. machine # [ 71.184618] systemd[636]: Reexecution requested from client PID 3833 ('switch-to-confi')... machine # [ 71.187871] systemd[636]: Reexecuting. machine # [ 71.223734] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 71.409213] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 71.416140] systemd[636]: Reload requested from client PID 3833 ('switch-to-confi')... machine # [ 71.418482] systemd[636]: Reloading... machine # [ 71.601930] systemd[636]: Reloading finished in 181 ms. machine # [ 71.608079] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 71.618117] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 71.619629] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 71.623038] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 71.674751] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 71.679848] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 71.681887] systemd[1]: Stopping Reactivate sysinit units... machine # [ 71.683577] systemd[1]: Reached target Reactivate sysinit units. machine # [ 71.686061] systemd[1]: Reached target Remote File Systems. machine # [ 71.690924] systemd[1]: Starting linger-users.service... machine # [ 71.692739] systemd[1]: Reached target Local File Systems. machine # [ 71.699173] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 71.705230] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 71.706756] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 71.709761] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 71.711886] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 71.715419] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 71.719545] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 71.721633] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 71.724198] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 71.791932] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 71.795666] systemd[1]: Finished linger-users.service. machine # [ 72.093654] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 72.096224] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 72.352288] nixos[3764]: finished switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.98 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 72.395039] sudo[3901]: 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 # [ 72.406704] sudo[3901]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 72.419242] sudo[3901]: 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 # [ 72.462902] sudo[3907]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 72.476374] sudo[3907]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 72.487946] sudo[3907]: 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.07 seconds) machine: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 72.523309] sudo[3913]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 72.534408] sudo[3913]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 72.544302] sudo[3913]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 72.577073] sudo[3919]: 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 # [ 72.587497] sudo[3919]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 72.597384] sudo[3919]: 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.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 72.631335] sudo[3925]: 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 # [ 72.640710] sudo[3925]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 72.648889] systemd[636]: Reload requested from client PID 3930 ('systemctl')... machine # [ 72.650479] systemd[636]: Reloading... machine # [ 72.793599] systemd[636]: Reloading finished in 141 ms. machine # [ 72.799047] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 72.806748] 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 daemon-reload, in 0.21 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 72.846460] sudo[3935]: 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 # [ 72.857523] sudo[3935]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 72.870629] systemd[636]: Starting migrated.service... machine # [ 72.898948] systemd[636]: Finished migrated.service. machine # [ 72.902581] sudo[3935]: 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.09 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 72.940385] sudo[3943]: 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 # [ 72.949857] sudo[3943]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 72.959781] sudo[3943]: 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 # [ 72.994032] sudo[3949]: 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 # [ 73.004781] sudo[3949]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 73.014567] sudo[3949]: 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.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 73.051928] sudo[3955]: 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 # [ 73.063276] sudo[3955]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 73.082816] sudo[3955]: 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: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1 machine # [ 73.582742] nixos[3964]: switching to system configuration /nix/store/nx9yamzsyd5gp8bpv0c4b15jny7yiiar-nixos-system-machine-test machine # [ 73.586377] systemd[1]: Stopped target Remote File Systems. machine # [ 73.587533] systemd[1]: Stopped target Local File Systems. machine # [ 74.034064] systemd[1]: Reload requested from client PID 3964 ('.switch-to-conf') (unit backdoor.service)... machine # [ 74.035808] systemd[1]: Reloading... machine # [ 78.333067] systemd[1]: Reloading finished in 4294 ms. machine # [ 78.456723] systemd[636]: Reexecution requested from client PID 4033 ('switch-to-confi')... machine # [ 78.459751] systemd[636]: Reexecuting. machine # [ 78.485826] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 78.631294] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 78.636957] systemd[636]: Reload requested from client PID 4033 ('switch-to-confi')... machine # [ 78.638593] systemd[636]: Reloading... machine # [ 78.774044] systemd[636]: Reloading finished in 133 ms. machine # [ 78.779840] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 78.787061] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 78.787955] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 78.790744] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 78.834934] systemd[636]: Reload requested from client PID 4045 ('systemctl') (unit nixos-activation.service)... machine # [ 78.836443] systemd[636]: Reloading... machine # [ 78.984945] systemd[636]: Reloading finished in 146 ms. machine # [ 78.990058] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 79.002770] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 79.004955] systemd[636]: Reload requested from client PID 4033 ('switch-to-confi')... machine # [ 79.006685] systemd[636]: Reloading... machine # [ 79.157723] systemd[636]: Reloading finished in 148 ms. machine # [ 79.162866] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 79.174074] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 79.175601] systemd[1]: Stopping Reactivate sysinit units... machine # [ 79.176628] systemd[1]: Reached target Reactivate sysinit units. machine # [ 79.181501] systemd[1]: Reached target Local File Systems. machine # [ 79.185722] systemd[1]: Reached target Remote File Systems. machine # [ 79.189605] systemd[1]: Starting linger-users.service... machine # [ 79.192895] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 79.196841] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 79.197707] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 79.199577] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 79.202373] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 79.204619] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 79.206237] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 79.211278] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 79.212530] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 79.271584] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 79.274268] systemd[1]: Finished linger-users.service. machine # [ 79.498706] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 79.501243] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 79.758179] nixos[3964]: finished switching to system configuration /nix/store/nx9yamzsyd5gp8bpv0c4b15jny7yiiar-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 6.68 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 79.827837] sudo[4112]: 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 # [ 79.844600] sudo[4112]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 79.853871] sudo[4112]: 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.09 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 79.902131] sudo[4118]: 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 # [ 79.913296] sudo[4118]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 79.926086] sudo[4118]: 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.07 seconds) machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 80.386386] nixos[4127]: switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test machine # [ 80.389091] systemd[1]: Stopped target Local File Systems. machine # [ 80.390292] systemd[1]: Stopped target Remote File Systems. machine # [ 80.734596] systemd[1]: Reload requested from client PID 4127 ('.switch-to-conf') (unit backdoor.service)... machine # [ 80.736295] systemd[1]: Reloading... machine # [ 81.238950] systemd[1]: Reloading finished in 500 ms. machine # [ 81.356242] systemd[636]: Stopped migrated.service. machine # [ 81.358078] systemd[636]: Reexecution requested from client PID 4196 ('switch-to-confi')... machine # [ 81.360511] systemd[636]: Reexecuting. machine # [ 81.388411] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 81.547629] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 81.553317] systemd[636]: Reload requested from client PID 4196 ('switch-to-confi')... machine # [ 81.555059] systemd[636]: Reloading... machine # [ 81.692801] systemd[636]: Reloading finished in 136 ms. machine # [ 81.698917] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 81.706858] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 81.707847] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 81.710478] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 81.743549] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 81.747730] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 81.748949] systemd[1]: Stopping Reactivate sysinit units... machine # [ 81.750218] systemd[1]: Reached target Reactivate sysinit units. machine # [ 81.752315] systemd[1]: Reached target Remote File Systems. machine # [ 81.758097] systemd[1]: Starting linger-users.service... machine # [ 81.765416] systemd[1]: Reached target Local File Systems. machine # [ 81.766312] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 81.770111] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 81.770944] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 81.772719] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 81.775542] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 81.778433] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 81.779983] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 81.782516] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 81.784651] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 81.836591] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 81.838588] systemd[1]: Finished linger-users.service. machine # [ 82.058856] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 82.060604] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 82.315567] nixos[4127]: finished switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.39 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 82.371296] sudo[4264]: 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 # [ 82.386079] sudo[4264]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 82.405406] sudo[4264]: 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.09 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user machine # [ 82.448242] sudo[4270]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user machine # [ 82.462971] sudo[4270]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 82.474102] sudo[4270]: 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.07 seconds) machine: must succeed: sudo -u usertest cp /nix/store/gizknzaqxgkdg471gzc71mvrg9648pl5-migrated.service ~usertest/.local/share/systemd/user/migrated.service machine # [ 82.518971] sudo[4276]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/gizknzaqxgkdg471gzc71mvrg9648pl5-migrated.service /home/usertest/.local/share/systemd/user/migrated.service machine # [ 82.531775] sudo[4276]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 82.545494] sudo[4276]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/gizknzaqxgkdg471gzc71mvrg9648pl5-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 82.592146] sudo[4282]: 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 # [ 82.604629] sudo[4282]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 82.613686] systemd[636]: Reload requested from client PID 4287 ('systemctl')... machine # [ 82.616055] systemd[636]: Reloading... machine # [ 82.770499] systemd[636]: Reloading finished in 153 ms. machine # [ 82.774106] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 82.782868] 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 daemon-reload, in 0.24 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 82.826844] sudo[4292]: 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 # [ 82.838645] sudo[4292]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 82.851691] systemd[636]: Starting migrated.service... machine # [ 82.892154] systemd[636]: Finished migrated.service. machine # [ 82.896210] sudo[4292]: 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.11 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 82.946792] sudo[4300]: 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 # [ 82.960483] sudo[4300]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 82.969574] sudo[4300]: 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 # [ 83.013333] sudo[4306]: 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 # [ 83.025970] sudo[4306]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 83.036453] sudo[4306]: 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.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 83.074590] sudo[4312]: 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 # [ 83.086672] sudo[4312]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 83.138245] sudo[4312]: 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.10 seconds) machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 83.658549] nixos[4321]: switching to system configuration /nix/store/r5qm0l8z1x8gzbaqb9pj38q7s6js36ml-nixos-system-machine-test machine # [ 83.661255] systemd[1]: Stopped target Local File Systems. machine # [ 83.663098] systemd[1]: Stopped target Remote File Systems. machine # [ 84.130741] systemd[1]: Reload requested from client PID 4321 ('.switch-to-conf') (unit backdoor.service)... machine # [ 84.132697] systemd[1]: Reloading... machine # [ 84.679327] systemd[1]: Reloading finished in 544 ms. machine # [ 84.788692] systemd[636]: Reexecution requested from client PID 4390 ('switch-to-confi')... machine # [ 84.790545] systemd[636]: Reexecuting. machine # [ 84.819286] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 84.971358] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 84.977648] systemd[636]: Reload requested from client PID 4390 ('switch-to-confi')... machine # [ 84.979698] systemd[636]: Reloading... machine # [ 85.122720] systemd[636]: Reloading finished in 140 ms. machine # [ 85.127991] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 85.136085] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 85.137144] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 85.140487] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 85.173689] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 85.176449] systemd[636]: Reload requested from client PID 4390 ('switch-to-confi')... machine # [ 85.178307] systemd[636]: Reloading... machine # [ 85.321798] systemd[636]: Reloading finished in 142 ms. machine # [ 85.327871] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 85.336107] systemd[636]: Stopped migrated.service. machine # [ 85.336873] systemd[636]: Stopping migrated.service... machine # [ 85.339178] systemd[636]: Starting migrated.service... machine # [ 85.369952] systemd[636]: Finished migrated.service. machine # [ 85.376158] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 85.377944] systemd[1]: Stopping Reactivate sysinit units... machine # [ 85.378862] systemd[1]: Reached target Reactivate sysinit units. machine # [ 85.380791] systemd[1]: Reached target Local File Systems. machine # [ 85.383170] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 85.387059] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 85.387911] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 85.389090] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 85.391136] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 85.394749] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 85.396169] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 85.401598] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 85.403119] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 85.410761] systemd[1]: Reached target Remote File Systems. machine # [ 85.694781] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 85.696683] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 85.701301] systemd[1]: Starting linger-users.service... machine # [ 85.747483] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 85.749098] systemd[1]: Finished linger-users.service. machine # [ 86.006884] nixos[4321]: finished switching to system configuration /nix/store/r5qm0l8z1x8gzbaqb9pj38q7s6js36ml-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 2.87 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 86.065904] sudo[4464]: 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 # [ 86.083358] sudo[4464]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 86.093973] sudo[4464]: 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.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 86.134474] sudo[4470]: 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 # [ 86.145850] sudo[4470]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 86.166767] sudo[4470]: 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 # [ 86.200728] sudo[4476]: 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 # [ 86.210848] sudo[4476]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 86.220292] sudo[4476]: 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.05 seconds) machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 86.739581] nixos[4485]: switching to system configuration /nix/store/r5qm0l8z1x8gzbaqb9pj38q7s6js36ml-nixos-system-machine-test machine # [ 86.742467] systemd[1]: Stopped target Remote File Systems. machine # [ 86.744663] systemd[1]: Stopped target Local File Systems. machine # [ 87.118775] systemd[1]: Reload requested from client PID 4485 ('.switch-to-conf') (unit backdoor.service)... machine # [ 87.120684] systemd[1]: Reloading... machine # [ 87.666102] systemd[1]: Reloading finished in 542 ms. machine # [ 87.774348] systemd[636]: Reexecution requested from client PID 4554 ('switch-to-confi')... machine # [ 87.776840] systemd[636]: Reexecuting. machine # [ 87.803263] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 87.936152] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 87.941748] systemd[636]: Reload requested from client PID 4554 ('switch-to-confi')... machine # [ 87.943801] systemd[636]: Reloading... machine # [ 88.105097] systemd[636]: Reloading finished in 159 ms. machine # [ 88.110059] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 88.118186] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 88.119260] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 88.123183] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 88.169044] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 88.173646] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 88.174971] systemd[1]: Stopping Reactivate sysinit units... machine # [ 88.176245] systemd[1]: Reached target Reactivate sysinit units. machine # [ 88.178475] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 88.180202] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 88.183418] systemd[1]: Reached target Remote File Systems. machine # [ 88.187685] systemd[1]: Starting linger-users.service... machine # [ 88.193684] systemd[1]: Reached target Local File Systems. machine # [ 88.200070] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 88.205309] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 88.206977] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 88.208884] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 88.211451] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 88.213607] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 88.215086] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 88.265725] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 88.269072] systemd[1]: Finished linger-users.service. machine # [ 88.632877] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 88.634949] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 88.895697] nixos[4485]: finished switching to system configuration /nix/store/r5qm0l8z1x8gzbaqb9pj38q7s6js36ml-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 2.69 seconds) machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd machine # [ 88.985760] sudo[4622]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd machine # [ 89.000624] sudo[4622]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 89.018087] sudo[4622]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.11 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 89.083268] sudo[4628]: 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 # [ 89.101509] sudo[4628]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 89.114716] systemd[636]: Reload requested from client PID 4633 ('systemctl')... machine # [ 89.116620] systemd[636]: Reloading... machine # [ 89.335887] systemd[636]: Reloading finished in 216 ms. machine # [ 89.342097] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 89.352706] sudo[4628]: 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.33 seconds) machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 89.860263] nixos[4641]: switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test machine # [ 89.863272] systemd[1]: Stopped target Remote File Systems. machine # [ 89.865455] systemd[1]: Stopped target Local File Systems. machine # [ 90.313578] systemd[1]: Reload requested from client PID 4641 ('.switch-to-conf') (unit backdoor.service)... machine # [ 90.315936] systemd[1]: Reloading... machine # [ 90.868349] systemd[1]: Reloading finished in 549 ms. machine # [ 90.998330] systemd[636]: Stopped migrated.service. machine # [ 91.000960] systemd[636]: Reexecution requested from client PID 4710 ('switch-to-confi')... machine # [ 91.002220] systemd[636]: Reexecuting. machine # [ 91.027163] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 91.163210] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 91.168611] systemd[636]: Reload requested from client PID 4710 ('switch-to-confi')... machine # [ 91.170453] systemd[636]: Reloading... machine # [ 91.301620] systemd[636]: Reloading finished in 129 ms. machine # [ 91.305871] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 91.312581] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 91.314201] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 91.317051] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 91.355366] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 91.358975] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 91.360167] systemd[1]: Stopping Reactivate sysinit units... machine # [ 91.360938] systemd[1]: Reached target Reactivate sysinit units. machine # [ 91.365074] systemd[1]: Reached target Local File Systems. machine # [ 91.367064] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 91.370877] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 91.371722] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 91.372899] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 91.376739] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 91.379144] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 91.382229] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 91.383466] systemd[1]: Reached target Remote File Systems. machine # [ 91.389944] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 91.391332] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 91.643716] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 91.644957] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 91.649655] systemd[1]: Starting linger-users.service... machine # [ 91.705444] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 91.707314] systemd[1]: Finished linger-users.service. machine # [ 91.964709] nixos[4641]: finished switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.61 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 92.031781] sudo[4778]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 92.048579] sudo[4778]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 92.057827] sudo[4778]: 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.09 seconds) machine: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 92.090479] sudo[4784]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 92.102287] sudo[4784]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 92.113154] sudo[4784]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 92.151558] sudo[4790]: 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 # [ 92.163329] sudo[4790]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 92.173322] sudo[4790]: 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.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 92.210562] sudo[4796]: 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 # [ 92.221567] sudo[4796]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 92.228979] systemd[636]: Reload requested from client PID 4801 ('systemctl')... machine # [ 92.231151] systemd[636]: Reloading... machine # [ 92.372471] systemd[636]: Reloading finished in 140 ms. machine # [ 92.377060] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 92.386240] 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 daemon-reload, in 0.21 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 92.427410] sudo[4806]: 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 # [ 92.438518] sudo[4806]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 92.451507] systemd[636]: Starting migrated.service... machine # [ 92.484041] systemd[636]: Finished migrated.service. machine # [ 92.488061] sudo[4806]: 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.10 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 92.527189] sudo[4814]: 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 # [ 92.538513] sudo[4814]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 92.548311] sudo[4814]: 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 # [ 92.589638] sudo[4820]: 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 # [ 92.602549] sudo[4820]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 92.613054] sudo[4820]: 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.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 92.652377] sudo[4826]: 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 # [ 92.663503] sudo[4826]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 92.682453] sudo[4826]: 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: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 93.123884] nixos[4835]: switching to system configuration /nix/store/r5qm0l8z1x8gzbaqb9pj38q7s6js36ml-nixos-system-machine-test machine # [ 93.126272] systemd[1]: Stopped target Local File Systems. machine # [ 93.128310] systemd[1]: Stopped target Remote File Systems. machine # [ 93.491791] systemd[1]: Reload requested from client PID 4835 ('.switch-to-conf') (unit backdoor.service)... machine # [ 93.493697] systemd[1]: Reloading... machine # [ 93.970728] systemd[1]: Reloading finished in 474 ms. machine # [ 94.068324] systemd[636]: Reexecution requested from client PID 4904 ('switch-to-confi')... machine # [ 94.070443] systemd[636]: Reexecuting. machine # [ 94.098342] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 94.232793] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 94.238890] systemd[636]: Reload requested from client PID 4904 ('switch-to-confi')... machine # [ 94.240170] systemd[636]: Reloading... machine # [ 94.378163] systemd[636]: Reloading finished in 136 ms. machine # [ 94.384119] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 94.392349] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 94.394146] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 94.396579] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 94.429852] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 94.431828] systemd[636]: Reload requested from client PID 4904 ('switch-to-confi')... machine # [ 94.434235] systemd[636]: Reloading... machine # [ 94.575950] systemd[636]: Reloading finished in 140 ms. machine # [ 94.582112] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 94.595267] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 94.596967] systemd[1]: Stopping Reactivate sysinit units... machine # [ 94.598328] systemd[1]: Reached target Reactivate sysinit units. machine # [ 94.600091] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 94.601826] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 94.605443] systemd[1]: Reached target Remote File Systems. machine # [ 94.609391] systemd[1]: Starting linger-users.service... machine # [ 94.615051] systemd[1]: Reached target Local File Systems. machine # [ 94.622258] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 94.627401] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 94.628498] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 94.631958] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 94.634952] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 94.636990] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 94.638838] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 94.688532] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 94.691764] systemd[1]: Finished linger-users.service. machine # [ 94.938752] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 94.941968] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 95.195283] nixos[4835]: finished switching to system configuration /nix/store/r5qm0l8z1x8gzbaqb9pj38q7s6js36ml-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 2.51 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 95.240943] sudo[4976]: 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 # [ 95.252775] sudo[4976]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 95.271753] 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 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 # [ 95.312422] sudo[4982]: 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 # [ 95.324391] sudo[4982]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 95.335157] sudo[4982]: 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.06 seconds) machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd machine # [ 95.375328] sudo[4988]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd machine # [ 95.386533] sudo[4988]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 95.397556] sudo[4988]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 95.439592] sudo[4994]: 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 # [ 95.451726] sudo[4994]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 95.460183] systemd[636]: Reload requested from client PID 4999 ('systemctl')... machine # [ 95.462373] systemd[636]: Reloading... machine # [ 95.645048] systemd[636]: Reloading finished in 181 ms. machine # [ 95.649863] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 95.658438] sudo[4994]: 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.26 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service machine # [ 95.699743] sudo[5004]: 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 # [ 95.711366] sudo[5004]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 95.722847] systemd[636]: Stopped migrated.service. machine # [ 95.726277] sudo[5004]: 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.07 seconds) machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 96.182212] nixos[5013]: switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test machine # [ 96.184650] systemd[1]: Stopped target Remote File Systems. machine # [ 96.186425] systemd[1]: Stopped target Local File Systems. machine # [ 96.568894] systemd[1]: Reload requested from client PID 5013 ('.switch-to-conf') (unit backdoor.service)... machine # [ 96.570616] systemd[1]: Reloading... machine # [ 97.047869] systemd[1]: Reloading finished in 475 ms. machine # [ 97.154609] systemd[636]: Reexecution requested from client PID 5082 ('switch-to-confi')... machine # [ 97.156389] systemd[636]: Reexecuting. machine # [ 97.181143] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 97.304561] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 97.309951] systemd[636]: Reload requested from client PID 5082 ('switch-to-confi')... machine # [ 97.311373] systemd[636]: Reloading... machine # [ 97.436592] systemd[636]: Reloading finished in 123 ms. machine # [ 97.441868] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 97.448204] systemd[636]: Stopped Run user-specific NixOS activation. machine # [ 97.450069] systemd[636]: Stopping Run user-specific NixOS activation... machine # [ 97.452206] systemd[636]: Starting Run user-specific NixOS activation... machine # [ 97.483576] systemd[636]: Finished Run user-specific NixOS activation. machine # [ 97.487573] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 97.488536] systemd[1]: Stopping Reactivate sysinit units... machine # [ 97.489448] systemd[1]: Reached target Reactivate sysinit units. machine # [ 97.491430] systemd[1]: Reached target Remote File Systems. machine # [ 97.495262] systemd[1]: Starting linger-users.service... machine # [ 97.502923] systemd[1]: Reached target Local File Systems. machine # [ 97.510637] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 97.515990] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 97.516949] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 97.519282] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 97.522890] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 97.525248] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 97.528399] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 97.529754] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 97.531357] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 97.572395] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 97.574624] systemd[1]: Finished linger-users.service. machine # [ 97.800098] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 97.802386] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 98.056713] nixos[5013]: finished switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.34 seconds) (finished: subtest: user services, in 72.76 seconds) (finished: run the VM test script, in 98.94 seconds) test script finished in 99.70s cleanup kill QemuMachine (pid 45) machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/b5bpi6zfajzzrwwpgba2q6li3nnya4bs-python3-3.14.7/bin/python3.14) (finished: cleanup, in 0.17 seconds) additionally exposed symbols: machine, other, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh