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.uRPqm6rYed', 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: 4033415b-4a95-4cab-bca8-4c27543dc2bd machine # Superblock backups stored on blocks: machine # 32768, 98304, 163840, 229376 machine # machine # Allocating group tables: 0/8 done machine # Writing inode tables: 0/8 done machine # Creating journal (8192 blocks): done machine # Writing superblocks and filesystem accounting information: 0/8 done machine # machine # Virtualisation disk image created. machine # Starting virtiofs daemons... machine # [2026-09-15T03:07:34Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-15T03:07:34Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-15T03:07:34Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-15T03:07:34Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-15T03:07:34Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-15T03:07:34Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-15T03:07:34Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-15T03:07:34Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-15T03:07:34Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-15T03:07:34Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-15T03:07:34Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-15T03:07:34Z INFO virtiofsd] Client connected, servicing requests machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) machine # machine # machine # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA00 machine # Press Ctrl-B to configure iPXE (PCI 00:02.0)... machine # machine # machine # machine # machine # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB00 machine # Press Ctrl-B to configure iPXE (PCI 00:05.0)... machine # machine # machine # Booting from ROM... machine # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.51 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 11 09:49:46 UTC 2026 machine # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/init regInfo=/nix/store/yhvprbdijx9p7r34dqpk77b6gcwi2vfh-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 472763266 cycles machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns machine # [ 0.000004] tsc: Detected 2400.012 MHz processor machine # [ 0.000804] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 machine # [ 0.000837] 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.002724] found SMP MP-table at [mem 0x000f5450-0x000f545f] machine # [ 0.002735] Using GB pages for direct mapping machine # [ 0.002772] RAMDISK: [mem 0x3e3d9000-0x3ffcffff] machine # [ 0.002779] ACPI: Early table checksum verification disabled machine # [ 0.002781] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) machine # [ 0.002784] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002788] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002794] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002797] ACPI: FACS 0x000000003FFE0000 000040 machine # [ 0.002798] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002800] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002801] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002803] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002804] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d] machine # [ 0.002805] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219] machine # [ 0.002806] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f] machine # [ 0.002806] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385] machine # [ 0.002807] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd] machine # [ 0.002807] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9] machine # [ 0.002808] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421] machine # [ 0.003030] No NUMA configuration found machine # [ 0.003031] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff] machine # [ 0.003033] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff] machine # [ 0.005206] Zone ranges: machine # [ 0.005207] DMA [mem 0x0000000000001000-0x0000000000ffffff] machine # [ 0.005209] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff] machine # [ 0.005210] Normal empty machine # [ 0.005211] Device empty machine # [ 0.005211] Movable zone start for each node machine # [ 0.005212] Early memory node ranges machine # [ 0.005212] node 0: [mem 0x0000000000001000-0x000000000009efff] machine # [ 0.005213] node 0: [mem 0x0000000000100000-0x000000003ffd7fff] machine # [ 0.005215] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff] machine # [ 0.005233] On node 0, zone DMA: 1 pages in unavailable ranges machine # [ 0.005473] On node 0, zone DMA: 97 pages in unavailable ranges machine # [ 0.021678] On node 0, zone DMA32: 40 pages in unavailable ranges machine # [ 0.022118] ACPI: PM-Timer IO Port: 0x608 machine # [ 0.022127] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) machine # [ 0.022151] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 machine # [ 0.022154] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) machine # [ 0.022155] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) machine # [ 0.022156] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) machine # [ 0.022157] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) machine # [ 0.022158] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) machine # [ 0.022160] ACPI: Using ACPI (MADT) for SMP configuration information machine # [ 0.022161] ACPI: HPET id: 0x8086a201 base: 0xfed00000 machine # [ 0.022164] TSC deadline timer available machine # [ 0.022168] CPU topo: Max. logical packages: 1 machine # [ 0.022168] CPU topo: Max. logical dies: 1 machine # [ 0.022169] CPU topo: Max. dies per package: 1 machine # [ 0.022172] CPU topo: Max. threads per core: 1 machine # [ 0.022173] CPU topo: Num. cores per package: 1 machine # [ 0.022173] CPU topo: Num. threads per package: 1 machine # [ 0.022173] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs machine # [ 0.022188] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() machine # [ 0.022214] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] machine # [ 0.022216] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] machine # [ 0.022217] [mem 0x40000000-0xafffffff] available for PCI devices machine # [ 0.022218] Booting paravirtualized kernel on KVM machine # [ 0.022220] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns machine # [ 0.026642] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 machine # [ 0.028658] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152 machine # [ 0.028697] kvm-guest: PV spinlocks disabled, single CPU machine # [ 0.028699] 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/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/init regInfo=/nix/store/yhvprbdijx9p7r34dqpk77b6gcwi2vfh-closure-info/registration console=ttyS0,115200n8 console=tty0 machine # [ 0.028788] Unknown kernel command line parameters "regInfo=/nix/store/yhvprbdijx9p7r34dqpk77b6gcwi2vfh-closure-info/registration", will be passed to user space. machine # [ 0.028800] random: crng init done machine # [ 0.028801] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes machine # [ 0.029790] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.030294] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.030322] Fallback order for Node 0: 0 machine # [ 0.030325] Built 1 zonelists, mobility grouping on. Total pages: 262006 machine # [ 0.030326] Policy zone: DMA32 machine # [ 0.032589] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.034980] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 machine # [ 0.037061] allocated 2097152 bytes of page_ext machine # [ 0.046375] ftrace: allocating 48736 entries in 192 pages machine # [ 0.046376] ftrace: allocated 192 pages with 2 groups machine # [ 0.047158] Dynamic Preempt: lazy machine # [ 0.047291] rcu: Preemptible hierarchical RCU implementation. machine # [ 0.047291] rcu: RCU event tracing is enabled. machine # [ 0.047292] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. machine # [ 0.047293] Trampoline variant of Tasks RCU enabled. machine # [ 0.047294] Rude variant of Tasks RCU enabled. machine # [ 0.047294] Tracing variant of Tasks RCU enabled. machine # [ 0.047294] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. machine # [ 0.047295] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 machine # [ 0.047341] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.047343] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.047344] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.051622] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16 machine # [ 0.051877] rcu: srcu_init: Setting srcu_struct sizes based on contention. machine # [ 0.051884] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns machine # [ 0.051983] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.055444] Console: colour VGA+ 80x25 machine # [ 0.055448] printk: legacy console [tty0] enabled machine # [ 0.084503] printk: legacy console [ttyS0] enabled machine # [ 0.190142] ACPI: Core revision 20250807 machine # [ 0.191026] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns machine # [ 0.192596] APIC: Switch to symmetric I/O mode setup machine # [ 0.193667] x2apic enabled machine # [ 0.194405] APIC: Switched APIC routing to: physical x2apic machine # [ 0.196268] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 machine # [ 0.197299] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns machine # [ 0.199046] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012) machine # [ 0.200127] x86/cpu: User Mode Instruction Prevention (UMIP) activated machine # [ 0.202169] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 machine # [ 0.203043] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 machine # [ 0.204047] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto machine # [ 0.205043] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl machine # [ 0.206043] Transient Scheduler Attacks: Vulnerable: No microcode machine # [ 0.207043] Spectre V2 : Mitigation: Enhanced / Automatic IBRS machine # [ 0.208043] Speculative Return Stack Overflow: Mitigation: Safe RET machine # [ 0.209043] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization machine # [ 0.211051] Spectre V2 : Enabling IBPB for BPF machine # [ 0.212044] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier machine # [ 0.213044] active return thunk: srso_alias_return_thunk machine # [ 0.214735] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' machine # [ 0.216023] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' machine # [ 0.216848] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' machine # [ 0.218043] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' machine # [ 0.219043] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' machine # [ 0.220043] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' machine # [ 0.221043] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' machine # [ 0.222043] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 machine # [ 0.223043] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 machine # [ 0.224832] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 machine # [ 0.225835] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 machine # [ 0.226836] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 machine # [ 0.227844] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format. machine # [ 0.261998] Freeing SMP alternatives memory: 44K machine # [ 0.262611] pid_max: default: 32768 minimum: 301 machine # [ 0.263133] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.264136] landlock: Up and running. machine # [ 0.265517] Yama: becoming mindful. machine # [ 0.266263] LSM support for eBPF active machine # [ 0.267137] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.268063] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.270822] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1) machine # [ 0.271551] Performance Events: Fam17h+ core perfctr, AMD PMU driver. machine # [ 0.272047] ... version: 2 machine # [ 0.272789] ... bit width: 48 machine # [ 0.273046] ... generic counters: 6 machine # [ 0.273815] ... generic bitmap: 000000000000003f machine # [ 0.274055] ... fixed-purpose counters: 0 machine # [ 0.274813] ... fixed-purpose bitmap: 0000000000000000 machine # [ 0.275745] ... value mask: 0000ffffffffffff machine # [ 0.276046] ... max period: 00007fffffffffff machine # [ 0.276980] ... global_ctrl mask: 000000000000003f machine # [ 0.277909] signal: max sigframe size: 3376 machine # [ 0.278674] rcu: Hierarchical SRCU implementation. machine # [ 0.279049] rcu: Max phase no-delay instances is 400. machine # [ 0.284442] smp: Bringing up secondary CPUs ... machine # [ 0.285059] smp: Brought up 1 node, 1 CPU machine # [ 0.285780] smpboot: Total of 1 processors activated (4800.02 BogoMIPS) machine # [ 0.286200] Memory: 941508K/1048024K available (17231K kernel code, 2726K rwdata, 13600K rodata, 3644K init, 2988K bss, 99160K reserved, 0K cma-reserved) machine # [ 0.287252] devtmpfs: initialized machine # [ 0.288071] x86/mm: Memory block size: 128MB machine # [ 0.289775] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.291039] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.292130] pinctrl core: initialized pinctrl subsystem machine # [ 0.293268] PM: RTC time: 03:07:34, date: 2026-09-15 machine # [ 0.296912] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.298216] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.299060] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.300192] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.301055] audit: initializing netlink subsys (disabled) machine # [ 0.302220] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.302222] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.303049] audit: type=2000 audit(1789441655.342:1): state=initialized audit_enabled=0 res=1 machine # [ 0.305047] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.305048] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.306045] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.307061] cpuidle: using governor menu machine # [ 0.309299] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 machine # [ 0.310278] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] machine # [ 0.311049] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry machine # [ 0.312057] PCI: Using configuration type 1 for base access machine # [ 0.313216] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. machine # [ 0.320319] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.321047] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.326047] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.327046] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.335628] ACPI: Added _OSI(Module Device) machine # [ 0.336047] ACPI: Added _OSI(Processor Device) machine # [ 0.336796] ACPI: Added _OSI(Processor Aggregator Device) machine # [ 0.343629] ACPI: 1 ACPI AML tables successfully acquired and loaded machine # [ 0.347012] ACPI: Interpreter enabled machine # [ 0.347566] ACPI: PM: (supports S0 S3 S4 S5) machine # [ 0.348046] ACPI: Using IOAPIC for interrupt routing machine # [ 0.348956] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug machine # [ 0.354046] PCI: Using E820 reservations for host bridge windows machine # [ 0.355171] ACPI: Enabled 2 GPEs in block 00 to 3F machine # [ 0.363883] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) machine # [ 0.365412] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] machine # [ 0.366125] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] machine # [ 0.367167] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] machine # [ 0.368382] PCI host bridge to bus 0000:00 machine # [ 0.369050] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] machine # [ 0.370046] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] machine # [ 0.371045] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] machine # [ 0.372046] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window] machine # [ 0.373045] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] machine # [ 0.374046] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] machine # [ 0.375046] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.376109] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.377477] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint machine # [ 0.380132] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] machine # [ 0.381057] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] machine # [ 0.382051] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] machine # [ 0.383334] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] machine # [ 0.385074] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.388093] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f] machine # [ 0.388995] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] machine # [ 0.389835] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] machine # [ 0.390961] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] machine # [ 0.392419] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.394934] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f] machine # [ 0.395756] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] machine # [ 0.396067] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] machine # [ 0.397615] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.400086] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f] machine # [ 0.401052] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] machine # [ 0.402070] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] machine # [ 0.403589] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.405944] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f] machine # [ 0.406758] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] machine # [ 0.407066] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref] machine # [ 0.408051] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref] machine # [ 0.409597] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.411088] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] machine # [ 0.412069] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref] machine # [ 0.413603] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.415059] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] machine # [ 0.416080] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref] machine # [ 0.417711] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.419086] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] machine # [ 0.420074] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref] machine # [ 0.421620] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.423759] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] machine # [ 0.424074] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref] machine # [ 0.425615] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.427827] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf] machine # [ 0.428800] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] machine # [ 0.429072] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref] machine # [ 0.430624] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.432676] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f] machine # [ 0.433056] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff] machine # [ 0.434097] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref] machine # [ 0.435603] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.436751] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f] machine # [ 0.437266] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.438895] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf] machine # [ 0.439841] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.441741] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df] machine # [ 0.442258] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.444800] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff] machine # [ 0.445320] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint machine # [ 0.446358] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO machine # [ 0.447289] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint machine # [ 0.449101] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff] machine # [ 0.449908] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff] machine # [ 0.451072] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint machine # [ 0.453476] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] machine # [ 0.457029] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 machine # [ 0.457845] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 machine # [ 0.458830] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 machine # [ 0.459832] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 machine # [ 0.460825] ACPI: PCI: Interrupt link LNKE configured for IRQ 10 machine # [ 0.461141] ACPI: PCI: Interrupt link LNKF configured for IRQ 10 machine # [ 0.462154] ACPI: PCI: Interrupt link LNKG configured for IRQ 11 machine # [ 0.463147] ACPI: PCI: Interrupt link LNKH configured for IRQ 11 machine # [ 0.464089] ACPI: PCI: Interrupt link GSIA configured for IRQ 16 machine # [ 0.465024] ACPI: PCI: Interrupt link GSIB configured for IRQ 17 machine # [ 0.465749] ACPI: PCI: Interrupt link GSIC configured for IRQ 18 machine # [ 0.466058] ACPI: PCI: Interrupt link GSID configured for IRQ 19 machine # [ 0.466968] ACPI: PCI: Interrupt link GSIE configured for IRQ 20 machine # [ 0.467748] ACPI: PCI: Interrupt link GSIF configured for IRQ 21 machine # [ 0.468057] ACPI: PCI: Interrupt link GSIG configured for IRQ 22 machine # [ 0.468975] ACPI: PCI: Interrupt link GSIH configured for IRQ 23 machine # [ 0.470820] iommu: Default domain type: Translated machine # [ 0.471699] iommu: DMA domain TLB invalidation policy: lazy mode machine # [ 0.472330] ACPI: bus type USB registered machine # [ 0.473092] usbcore: registered new interface driver usbfs machine # [ 0.473978] usbcore: registered new interface driver hub machine # [ 0.474702] usbcore: registered new device driver usb machine # [ 0.475881] NetLabel: Initializing machine # [ 0.476445] NetLabel: domain hash size = 128 machine # [ 0.477045] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO machine # [ 0.477961] NetLabel: unlabeled traffic allowed by default machine # [ 0.478720] PCI: Using ACPI for IRQ routing machine # [ 0.522291] pci 0000:00:01.0: vgaarb: setting as boot VGA device machine # [ 0.523042] pci 0000:00:01.0: vgaarb: bridge control possible machine # [ 0.523042] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none machine # [ 0.523050] vgaarb: loaded machine # [ 0.523744] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 machine # [ 0.524047] hpet0: 3 comparators, 64-bit 100.000000 MHz counter machine # [ 0.528127] clocksource: Switched to clocksource kvm-clock machine # [ 0.531469] VFS: Disk quotas dquot_6.6.0 machine # [ 0.532138] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.533447] pnp: PnP ACPI init machine # [ 0.534226] ACPI: IRQ 4 override to edge(!), high(!) machine # [ 0.535129] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved machine # [ 0.536617] pnp: PnP ACPI: found 5 devices machine # [ 0.544063] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns machine # [ 0.545459] clocksource: Switched to clocksource acpi_pm machine # [ 0.546384] NET: Registered PF_INET protocol family machine # [ 0.547391] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.563635] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.565077] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.566339] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.567556] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.568760] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.569821] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.571028] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.572096] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.573336] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.574383] NET: Registered PF_XDP protocol family machine # [ 0.575271] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] machine # [ 0.576324] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] machine # [ 0.577408] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] machine # [ 0.578534] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window] machine # [ 0.579720] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] machine # [ 0.580888] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] machine # [ 0.582647] ACPI: \_SB_.GSIA: Enabled at IRQ 16 machine # [ 0.585003] ACPI: \_SB_.GSIB: Enabled at IRQ 17 machine # [ 0.587111] ACPI: \_SB_.GSIC: Enabled at IRQ 18 machine # [ 0.589317] ACPI: \_SB_.GSID: Enabled at IRQ 19 machine # [ 0.591104] PCI: CLS 0 bytes, default 64 machine # [ 0.592147] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns machine # [ 0.593944] Trying to unpack rootfs image as initramfs... machine # [ 0.637964] Initialise system trusted keyrings machine # [ 0.640230] workingset: timestamp_bits=40 max_order=18 bucket_order=0 machine # [ 0.663354] Key type asymmetric registered machine # [ 0.664068] Asymmetric key parser 'x509' registered machine # [ 0.664966] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) machine # [ 0.670322] io scheduler mq-deadline registered machine # [ 0.671107] io scheduler kyber registered machine # [ 0.674716] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.676002] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A machine # [ 0.679968] Linux agpgart interface v0.103 machine # [ 0.680768] ACPI: bus type drm_connector registered machine # [ 0.683581] usbcore: registered new interface driver usbserial_generic machine # [ 0.684663] usbserial: USB Serial support registered for generic machine # [ 0.688188] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS. machine # [ 0.688188] Please enable it if your BIOS has the CPPC option. machine # [ 0.690456] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled machine # [ 0.694350] drop_monitor: Initializing network drop monitor service machine # [ 0.695505] NET: Registered PF_INET6 protocol family machine # [ 0.698603] Segment Routing with IPv6 machine # [ 0.701202] In-situ OAM (IOAM) with IPv6 machine # [ 0.702259] IPI shorthand broadcast: enabled machine # [ 0.710577] sched_clock: Marking stable (568014312, 142158704)->(784194879, -74021863) machine # [ 0.715283] registered taskstats version 1 machine # [ 0.716286] Loading compiled-in X.509 certificates machine # [ 0.734191] Demotion targets for Node 0: null machine # [ 0.735062] Key type .fscrypt registered machine # [ 0.738178] Key type fscrypt-provisioning registered machine # [ 0.739189] ima: No TPM chip found, activating TPM-bypass! machine # [ 0.740139] ima: Allocated hash algorithm: sha1 machine # [ 0.742207] ima: No architecture policies found machine # [ 0.743211] PM: Magic number: 10:525:108 machine # [ 0.744786] RAS: Correctable Errors collector initialized. machine # [ 0.754843] clk: Disabling unused clocks machine # [ 0.758191] PM: genpd: Disabling unused power domains machine # [ 0.905877] Freeing initrd memory: 28636K machine # [ 0.908977] Freeing unused decrypted memory: 2028K machine # [ 0.911595] Freeing unused kernel image (initmem) memory: 3644K machine # [ 0.912713] Write protecting the kernel read-only data: 32768k machine # [ 0.914595] Freeing unused kernel image (text/rodata gap) memory: 1200K machine # [ 0.916118] Freeing unused kernel image (rodata/data gap) memory: 736K machine # [ 0.967253] x86/mm: Checked W+X mappings: passed, no W+X pages found. machine # [ 0.968354] Run /init as init process machine # [ 0.978775] systemd[1]: Inserted module 'autofs4' machine # [ 0.998939] fuse: init (API version 7.45) machine # [ 1.006422] ACPI: \_SB_.GSIG: Enabled at IRQ 22 machine # [ 1.008679] ACPI: \_SB_.GSIH: Enabled at IRQ 23 machine # [ 1.011598] ACPI: \_SB_.GSIE: Enabled at IRQ 20 machine # [ 1.013602] ACPI: \_SB_.GSIF: Enabled at IRQ 21 machine # [ 1.018450] virtiofs virtio5: discovered new tag: nix-store machine # [ 1.020426] virtiofs virtio5: virtio_fs_setup_dax: No cache capability machine # [ 1.027558] virtiofs virtio6: discovered new tag: shared machine # [ 1.029297] virtiofs virtio6: virtio_fs_setup_dax: No cache capability machine # [ 1.032785] virtiofs virtio7: discovered new tag: xchg machine # [ 1.034332] virtiofs virtio7: virtio_fs_setup_dax: No cache capability machine # [ 1.057354] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.392977] systemd[1]: systemd 262-e2c8482 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.397791] systemd[1]: Detected virtualization kvm. machine # [ 1.398645] systemd[1]: Detected architecture x86-64. machine # [ 1.399487] systemd[1]: Running in initrd. machine # [ 1.400588] systemd[1]: Initializing machine ID from random generator. machine # [ 1.401874] systemd[1]: Hostname set to . machine # [ 1.474932] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.511914] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.516506] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.517728] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.519209] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.520366] systemd[1]: Reached target Path Units. machine # [ 1.521298] systemd[1]: Reached target Slice Units. machine # [ 1.522213] systemd[1]: Reached target Swaps. machine # [ 1.523042] systemd[1]: Reached target Timer Units. machine # [ 1.524062] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.525342] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.526532] systemd[1]: Listening on Journal Sockets. machine # [ 1.527544] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.528579] systemd[1]: Reached target Socket Units. machine # [ 1.530522] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.534249] systemd[1]: Starting Load Kernel Module configfs... machine # [ 1.543247] systemd[1]: Starting Journal Service... machine # [ 1.553281] systemd[1]: Starting Load Kernel Modules... machine # [ 1.573259] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.582261] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.595573] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.598998] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 1.605263] systemd-journald[66]: Collecting audit messages is disabled. machine # [ 1.610581] systemd[1]: Finished Load Kernel Module configfs. machine # [ 1.613660] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config machine # [ 1.624975] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.628266] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.636362] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.670000] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.676301] systemd[1]: Finished Load Kernel Modules. machine # [ 1.683426] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.693254] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.719479] systemd[1]: Started Journal Service. machine # [ 1.576729] systemd-modules-load[67]: Inserted module 'dm_mod' machine # [ 1.582076] systemd-modules-load[67]: Inserted module 'virtio_balloon' machine # [ 1.584190] systemd-modules-load[67]: Inserted module 'virtio_gpu' machine # [ 1.593240] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.595161] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.599453] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.600454] systemd[1]: Reached target Local File Systems. machine # [ 1.604076] systemd[1]: Starting Create System Files and Directories... machine # [ 1.611070] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.636483] systemd[1]: Finished Create System Files and Directories. machine # [ 1.659252] systemd-udevd[81]: Using default interface naming scheme 'v261'. machine # [ 1.682429] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 1.735078] systemd[1]: Finished Coldplug All udev Devices. machine # [ 1.737721] systemd[1]: Reached target System Initialization. machine # [ 1.738603] systemd[1]: Reached target Basic System. machine # [ 2.066705] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 machine # [ 2.069283] virtio_blk virtio2: 1/0/0 default/read/poll queues machine # [ 2.070917] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 2.075967] serio: i8042 KBD port at 0x60,0x64 irq 1 machine # [ 2.083535] ehci-pci 0000:00:1d.7: EHCI Host Controller machine # [ 2.084287] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1 machine # [ 2.086017] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000 machine # [ 2.088195] serio: i8042 AUX port at 0x60,0x64 irq 12 machine # [ 2.093408] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 machine # [ 2.097248] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18 machine # [ 2.098371] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.101557] usb usb1: Product: EHCI Host Controller machine # [ 2.102366] usb usb1: Manufacturer: Linux 6.18.51 ehci_hcd machine # [ 2.103095] usb usb1: SerialNumber: 0000:00:1d.7 machine # [ 2.105454] hub 1-0:1.0: USB hub found machine # [ 2.107189] hub 1-0:1.0: 6 ports detected machine # [ 2.110761] uhci_hcd 0000:00:1d.0: UHCI Host Controller machine # [ 2.111480] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2 machine # [ 2.123377] uhci_hcd 0000:00:1d.0: detected 2 ports machine # [ 2.129696] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c180 machine # [ 2.136433] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 2.137524] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.153427] SCSI subsystem initialized machine # [ 2.155585] usb usb2: Product: UHCI Host Controller machine # [ 2.161197] usb usb2: Manufacturer: Linux 6.18.51 uhci_hcd machine # [ 2.161934] usb usb2: SerialNumber: 0000:00:1d.0 machine # [ 2.176267] hub 2-0:1.0: USB hub found machine # [ 2.178275] hub 2-0:1.0: 2 ports detected machine # [ 2.041111] (udev-worker)[86]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.043505] systemd[1]: Starting Virtual Console Setup... machine # [ 2.045614] (udev-worker)[84]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.048660] (udev-worker)[86]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.209617] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 machine # [ 2.218226] uhci_hcd 0000:00:1d.1: UHCI Host Controller machine # [ 2.218967] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3 machine # [ 2.088673] systemd-vconsole-setup[97]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.091307] systemd[1]: Finished Virtual Console Setup. machine # [ 2.237219] uhci_hcd 0000:00:1d.1: detected 2 ports machine # [ 2.238040] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a0 machine # [ 2.251710] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 2.252887] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.113060] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 2.113939] systemd[1]: Reached target Initrd Root Device. machine # [ 2.115163] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 2.264450] usb usb3: Product: UHCI Host Controller machine # [ 2.265136] usb usb3: Manufacturer: Linux 6.18.51 uhci_hcd machine # [ 2.271191] usb usb3: SerialNumber: 0000:00:1d.1 machine # [ 2.273127] hub 3-0:1.0: USB hub found machine # [ 2.277956] hub 3-0:1.0: 2 ports detected machine # [ 2.283347] uhci_hcd 0000:00:1d.2: UHCI Host Controller machine # [ 2.284128] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4 machine # [ 2.287214] uhci_hcd 0000:00:1d.2: detected 2 ports machine # [ 2.288045] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c0 machine # [ 2.292483] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 2.295850] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.297344] usb usb4: Product: UHCI Host Controller machine # [ 2.298079] usb usb4: Manufacturer: Linux 6.18.51 uhci_hcd machine # [ 2.161207] systemd-fsck[105]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 2.165447] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 2.310437] usb usb4: SerialNumber: 0000:00:1d.2 machine # [ 2.313336] hub 4-0:1.0: USB hub found machine # [ 2.314516] hub 4-0:1.0: 2 ports detected machine # [ 2.340927] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode machine # [ 2.342058] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) machine # [ 2.346214] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 2.354074] ahci 0000:00:1f.2: flags: 64bit ncq only machine # [ 2.360728] scsi host0: ahci machine # [ 2.362536] scsi host1: ahci machine # [ 2.364249] scsi host2: ahci machine # [ 2.365948] scsi host3: ahci machine # [ 2.367697] scsi host4: ahci machine # [ 2.369299] scsi host5: ahci machine # [ 2.369910] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 1 machine # [ 2.372095] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 1 machine # [ 2.373329] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 1 machine # [ 2.374448] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 1 machine # [ 2.375592] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 1 machine # [ 2.376746] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 1 machine # [ 2.479524] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 machine # [ 2.482447] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 machine # [ 2.485916] usb 1-1: Product: QEMU USB Tablet machine # [ 2.487703] usb 1-1: Manufacturer: QEMU machine # [ 2.489646] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1 machine # [ 2.523854] hid: raw HID events driver (C) Jiri Kosina machine # [ 2.453078] systemd[1]: Mounting /sysroot... machine # [ 2.687706] ata1: SATA link down (SStatus 0 SControl 300) machine # [ 2.690675] ata4: SATA link down (SStatus 0 SControl 300) machine # [ 2.693111] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) machine # [ 2.695576] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 machine # [ 2.697645] ata3.00: applying bridge limits machine # [ 2.699538] ata2: SATA link down (SStatus 0 SControl 300) machine # [ 2.701870] ata6: SATA link down (SStatus 0 SControl 300) machine # [ 2.704211] ata5: SATA link down (SStatus 0 SControl 300) machine # [ 2.706383] ata3.00: configured for UDMA/100 machine # [ 2.708758] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 machine # [ 2.778349] usbcore: registered new interface driver usbhid machine # [ 2.782173] usbhid: USB HID core driver machine # [ 2.792881] 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.796256] 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.802443] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray machine # [ 2.820370] cdrom: Uniform CD-ROM driver Revision: 3.20 machine # [ 2.836882] EXT4-fs (vda): mounted filesystem 4033415b-4a95-4cab-bca8-4c27543dc2bd r/w with ordered data mode. Quota mode: none. machine # [ 2.699099] systemd[1]: Mounted /sysroot. machine # [ 2.702075] systemd[1]: Reached target Initrd Root File System. machine # [ 2.704657] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 2.715676] systemd-sysroot-fstab-check[138]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 2.721069] systemd[1]: Reload requested from client PID 138 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 2.722533] systemd[1]: Reloading... machine # [ 2.795105] systemd[1]: Reloading finished in 74 ms. machine # [ 2.803192] systemd-sysroot-fstab-check[138]: Requesting initrd-fs.target/start/replace... machine # [ 2.807162] systemd-sysroot-fstab-check[138]: Requesting swap.target/start/replace... machine # [ 2.811897] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 2.813722] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 2.814702] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 3.454331] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.462180] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 3.470152] systemd[1]: Mounting /sysroot/run... machine # [ 3.480299] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.490803] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.534873] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.536294] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.538250] systemd[1]: Mounted /sysroot/run. machine # [ 3.539237] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.542222] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.545616] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.556756] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.558295] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.455428] systemd[1]: Mounting /sysroot/nix/store... machine # [ 4.506228] systemd[1]: Mounted /sysroot/nix/store. machine # [ 4.508942] systemd[1]: Reached target Initrd File Systems. machine # [ 4.513135] systemd[1]: Starting Find NixOS closure... machine # [ 4.518705] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 4.541674] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 4.544754] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. machine # [ 4.551292] systemd[1]: Finished Find NixOS closure. machine # [ 4.553226] systemd[1]: Reached target Initrd Default Target. machine # [ 4.554594] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 4.568943] systemd[1]: Stopped target Initrd Default Target. machine # [ 4.570240] systemd[1]: Stopped target Basic System. machine # [ 4.571251] systemd[1]: Stopped target Initrd Root Device. machine # [ 4.573121] systemd[1]: Stopped target Path Units. machine # [ 4.573839] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 4.574839] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 4.575840] systemd[1]: Stopped target Slice Units. machine # [ 4.576550] systemd[1]: Stopped target Socket Units. machine # [ 4.577418] systemd[1]: Stopped target System Initialization. machine # [ 4.578211] systemd[1]: Stopped target Swaps. machine # [ 4.579100] systemd[1]: Stopped target Timer Units. machine # [ 4.580115] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 4.581117] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 4.582106] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 4.583700] systemd[1]: Stopped Find NixOS closure. machine # [ 4.584735] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.586096] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 4.587261] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.589034] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 4.589989] systemd[1]: Stopped Load Kernel Modules. machine # [ 4.590891] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 4.592601] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 4.593968] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 4.594950] systemd[1]: Stopped Create System Files and Directories. machine # [ 4.595832] systemd[1]: Stopped target Local File Systems. machine # [ 4.598109] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 4.600064] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 4.601038] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 4.603506] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 4.604527] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.605551] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.611784] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 4.616070] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 4.617439] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 4.619553] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 4.626919] systemd[1]: Starting Cleanup udev Database... machine # [ 4.628152] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 4.629200] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 4.631115] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 4.632200] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 4.633161] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 4.634085] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 4.635350] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 4.636717] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.653269] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 4.655243] systemd[1]: Finished Cleanup udev Database. machine # [ 4.656224] systemd[1]: Reached target Switch Root. machine # [ 4.658069] systemd[1]: Starting NixOS Activation... machine # [ 4.730703] initrd-nixos-activation-start[189]: booting system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test machine # [ 4.757967] initrd-nixos-activation-start[189]: running activation script... machine # [ 4.953531] initrd-nixos-activation-start[212]: setting up /etc... machine # [ 5.051739] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 5.054070] systemd[1]: Finished NixOS Activation. machine # [ 5.055312] systemd[1]: Starting Switch Root... machine # [ 5.068889] systemd[1]: Switching root. machine # [ 5.342617] systemd-journald[66]: Received SIGTERM from PID 1 (systemd). machine # [ 5.419761] NET: Registered PF_VSOCK protocol family machine # [ 5.777283] systemd[1]: systemd 262-e2c8482 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.787577] systemd[1]: Detected virtualization kvm. machine # [ 5.789352] systemd[1]: Detected architecture x86-64. machine # [ 5.791229] systemd[1]: Detected first boot. machine # [ 5.795000] systemd[1]: Initializing machine ID from random generator. machine # [ 5.902836] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 5.982626] systemd[1]: Applying preset policy. machine # [ 6.142186] systemd[1]: Populated /etc with preset unit settings. machine # [ 6.312120] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 6.313476] systemd[1]: Stopped initrd-switch-root.service. machine # [ 6.315568] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 6.317624] systemd[1]: Created slice Slice /system/getty. machine # [ 6.318864] systemd[1]: Created slice User and Session Slice. machine # [ 6.319804] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 6.320988] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 6.322066] systemd[1]: Expecting device /dev/hvc0... machine # [ 6.322807] systemd[1]: Expecting device /dev/ttyS0... machine # [ 6.323532] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 6.324365] systemd[1]: Stopped target initrd-fs.target. machine # [ 6.325095] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 6.325930] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 6.326771] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 6.327686] systemd[1]: Reached target Path Units. machine # [ 6.328368] systemd[1]: Reached target Remote File Systems. machine # [ 6.329169] systemd[1]: Reached target Slice Units. machine # [ 6.329871] systemd[1]: Reached target Swaps. machine # [ 6.331460] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 6.333628] systemd[1]: Listening on Process Core Dump Socket. machine # [ 6.335222] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 6.336877] systemd[1]: Listening on Factory Reset Management. machine # [ 6.337799] systemd[1]: Listening on Hostname Service Socket. machine # [ 6.351700] systemd[1]: Starting Journal Log Access Socket... machine # [ 6.353296] systemd[1]: Listening on Journal Audit Socket. machine # [ 6.355775] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 6.356885] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 6.358006] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.359292] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 6.362923] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 6.363966] systemd[1]: Listening on udev Varlink Socket. machine # [ 6.366195] systemd[1]: Mounting Huge Pages File System... machine # [ 6.368394] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 6.375437] systemd[1]: Mounting Kernel Debug File System... machine # [ 6.382350] systemd[1]: Mounting Kernel Trace File System... machine # [ 6.390676] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 6.399705] systemd[1]: Starting Load Kernel Module configfs... machine # [ 6.404824] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 6.415182] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 6.425874] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 6.443046] systemd[1]: Mounting FUSE Control File System... machine # [ 6.446584] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 6.458282] systemd[1]: Starting Journal Service... machine # [ 6.464421] systemd[1]: Starting Load Kernel Modules... machine # [ 6.475706] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 6.487362] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 6.491317] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.501635] systemd[1]: Starting Coldplug All udev Devices... machine # [ 6.507731] systemd-journald[283]: Collecting audit messages is enabled. machine # [ 6.522842] systemd[1]: Listening on Journal Log Access Socket. machine # [ 6.527444] systemd[1]: Mounted Huge Pages File System. machine # [ 6.386901] systemd[1]: Queued start job for default target Multi-User System. machine # [ 6.389335] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 6.535484] systemd[1]: Started Journal Service. machine # [ 6.397788] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 6.399000] systemd[1]: Mounted Kernel Debug File System. machine # [ 6.407630] systemd[1]: Mounted Kernel Trace File System. machine # [ 6.409502] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 6.413131] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 6.556562] loop: module loaded machine # [ 6.419211] systemd[1]: Finished Load Kernel Module configfs. machine # [ 6.420227] systemd[1]: Mounted FUSE Control File System. machine # [ 6.421186] systemd-modules-load[284]: Inserted module 'loop' machine # [ 6.435950] systemd[1]: Mounting Kernel Configuration File System... machine # [ 6.443424] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 6.444661] systemd[1]: Finished Load Kernel Modules. machine # [ 6.454851] systemd[1]: Starting Firewall... machine # [ 6.603183] EXT4-fs (vda): re-mounted 4033415b-4a95-4cab-bca8-4c27543dc2bd. machine # [ 6.467066] systemd[1]: Starting Apply Kernel Variables... machine # [ 6.480158] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 6.492278] systemd-oomd[286]: No swap; memory pressure usage will be degraded machine # [ 6.498518] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 6.501168] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 6.509154] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 6.510084] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 6.517475] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 6.518339] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.714909] systemd-journald[283]: Received client request to flush runtime journal. machine # [ 6.671559] systemd[1]: Mounted Kernel Configuration File System. machine # [ 6.673518] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 6.675255] systemd[1]: Reached target First Boot Complete. machine # [ 6.676115] systemd[1]: Finished Apply Kernel Variables. machine # [ 6.676842] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 6.678124] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 6.681384] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 6.769928] systemd[1]: Finished Coldplug All udev Devices. machine # [ 6.777437] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 6.779271] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 6.781246] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 6.826139] systemd-udevd[323]: Using default interface naming scheme 'v261'. machine # [ 6.876083] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 6.988651] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 7.065959] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 7.092057] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped. machine # [ 7.118316] (udev-worker)[356]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 7.121136] (udev-worker)[356]: Network interface NamePolicy= disabled on kernel command line. machine # [ 7.125834] (udev-worker)[348]: Network interface NamePolicy= disabled on kernel command line. machine # [ 7.174524] systemd[1]: Mounting /run/wrappers... machine # [ 7.209342] systemd[1]: Mounted /run/wrappers. machine # [ 7.210156] systemd[1]: Reached target Local File Systems. machine # [ 7.215126] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 7.217100] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 7.217893] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 7.223271] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 7.228472] systemd[1]: Starting Create System Files and Directories... machine # [ 7.322119] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 7.323382] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 7.326179] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 7.327940] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 7.330377] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 7.332723] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.335137] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.346148] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 7.354666] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 7.359656] systemd[1]: Finished Create System Files and Directories. machine # [ 7.370361] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 7.373580] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 7.450185] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 7.475127] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 7.480620] systemd[1]: Starting Update is Completed... machine # [ 7.523449] systemd[1]: Finished Update is Completed. machine # [ 7.682870] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 machine # [ 7.726828] ACPI: button: Power Button [PWRF] machine # [ 7.739527] mousedev: PS/2 mouse device common for all mice machine # [ 7.784398] rtc_cmos PNP0B00:00: RTC can wake from S4 machine # [ 7.643694] systemd[1]: Finished Firewall. machine # [ 7.658225] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 7.659743] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 7.661870] systemd[1]: Reached target System Initialization. machine # [ 7.663395] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 7.665492] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 7.666388] systemd[1]: Reached target Timer Units. machine # [ 7.667174] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 7.668625] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 7.669798] systemd[1]: Reached target Socket Units. machine # [ 7.671153] systemd[1]: Reached target Basic System. machine # [ 7.814429] rtc_cmos PNP0B00:00: registered as rtc0 machine # [ 7.674315] systemd[1]: Started backdoor.service. machine # [ 7.683064] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 7.828388] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console machine # [ 7.688609] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 7.693092] systemd[1]: Starting Post-Boot Actions... machine # [ 7.837347] rtc_cmos PNP0B00:00: setting system clock to 2026-09-15T03:07:42 UTC (1789441662) machine # [ 7.838564] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs machine # [ 7.839966] systemd-journald[283]: Time jumped backwards, rotating. machine # [ 7.747571] systemd[1]: Started Reset console on configuration changes. machine # [ 7.748955] systemd[1]: Starting resolvconf update... machine # [ 7.792859] systemd[1]: Starting D-Bus System Message Bus... machine # connecting to host... machine # [ 7.820034] systemd[1]: Finished Post-Boot Actions. machine # [ 7.831291] nsncd[493]: Sep 15 03:07:42.634 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 7.836311] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 7.837205] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 7.838724] systemd[1]: Reached target User and Group Name Lookups. machine: Guest shell says: b'Spawning backdoor root shell...\n' machine: connected to guest root shell machine: (connecting took 8.51 seconds) machine: (finished: waiting for the VM to finish booting, in 8.74 seconds) machine # [ 7.866763] systemd[1]: Starting User Login Management... machine # [ 7.902290] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 7.907190] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input4 machine # [ 7.928003] parport_pc 00:02: reported by Plug and Play ACPI machine # [ 7.928091] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] machine # [ 8.012596] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized machine # [ 8.170813] Console: switching to colour dummy device 80x25 machine # [ 8.174899] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt machine # [ 8.175005] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD machine # [ 8.242742] [drm] Found bochs VGA, ID 0xb0c5. machine # [ 8.242744] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. machine # [ 8.002752] dbus-broker-launch[501]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 8.105880] dbus-broker-launch[501]: NSS returned no entry for 'systemd-timesync' machine # [ 8.108141] dbus-broker-launch[501]: Invalid user-name in /nix/store/abzvksrj3003zwg0iwcfzxlrk3ywxf85-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 8.113164] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 8.114422] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 8.119550] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 8.121146] systemd[1]: Stopping User and Group Name Lookups... machine # [ 8.121981] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 8.127216] systemd[1]: nscd.service: Deactivated successfully. machine # [ 8.128049] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 8.128845] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 8.129691] systemd[1]: Started D-Bus System Message Bus. machine # [ 8.130569] systemd-logind[522]: New seat seat0. machine # [ 8.276036] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6 machine # [ 8.135939] systemd[1]: Started User Login Management. machine # [ 8.139579] dbus-broker-launch[501]: Ready machine # [ 8.142208] systemd[1]: Starting linger-users.service... machine # [ 8.286358] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5 machine # [ 8.293979] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic machine # [ 8.299185] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0 machine # [ 8.169196] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 8.171778] nsncd[572]: Sep 15 03:07:42.975 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 8.174678] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 8.176946] systemd[1]: Reached target User and Group Name Lookups. machine # [ 8.208114] systemd[1]: Finished resolvconf update. machine # [ 8.212348] systemd[1]: Reached target Preparation for Network. machine # [ 8.217435] systemd[1]: Starting DHCP Client... machine # [ 8.220094] systemd[1]: Starting Address configuration of eth1... machine # [ 8.233116] systemd[1]: Starting Extra networking commands.... machine # [ 8.294066] systemd[1]: Created slice Slice /user/1001. machine # [ 8.294841] systemd[1]: Starting Virtual Console Setup... machine # [ 8.296282] systemd[1]: Starting User Runtime Directory /run/user/1001... machine # [ 8.312754] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 8.315990] systemd[1]: Finished linger-users.service. machine # [ 8.338293] systemd-logind[522]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) machine # [ 8.339611] systemd-logind[522]: Watching system buttons on /dev/input/event2 (Power Button) machine # [ 8.508056] Console: switching to colour frame buffer device 160x50 machine # [ 8.524574] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device machine # [ 8.386730] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 8.394410] systemd[1]: Stopped Virtual Console Setup. machine # [ 8.395145] systemd[1]: Starting Virtual Console Setup... machine # [ 8.560187] ppdev: user-space parallel port driver machine # [ 8.437068] systemd[1]: Finished User Runtime Directory /run/user/1001. machine # [ 8.454781] systemd[1]: Starting User Manager for UID 1001... machine # [ 8.471325] systemd-logind[522]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 8.643514] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660) machine # [ 8.663528] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0) machine # [ 8.551898] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 8.553380] systemd[1]: Stopped Virtual Console Setup. machine # [ 8.557688] systemd[1]: Starting Virtual Console Setup... machine # [ 8.562618] (systemd)[623]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 8.577378] network-addresses-eth1-start[606]: adding address 192.168.1.1/24... done machine # [ 8.584265] systemd-logind[522]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'. machine # [ 8.616812] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 8.690252] systemd[1]: Finished Address configuration of eth1. machine # [ 8.691149] network-addresses-eth1-start[606]: adding address 2001:db8:1::1/64... done machine # [ 8.812922] dhcpcd[631]: dhcpcd-10.3.2 starting machine # [ 8.822981] systemd[623]: Queued start job for default target Main User Target. machine # [ 8.834350] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 8.835811] systemd[623]: Created slice User Application Slice. machine # [ 8.836616] systemd[623]: Started Daily Cleanup of User's Temporary Directories. machine # [ 8.839413] systemd[623]: Reached target Paths. machine # [ 8.842191] systemd[623]: Reached target Timers. machine # [ 8.842861] systemd[623]: Starting D-Bus User Message Bus Socket... machine # [ 8.845424] systemd[623]: Starting Create User Files and Directories... machine # [ 8.853298] dhcpcd[689]: dev: loaded udev machine # [ 9.005468] kvm_amd: TSC scaling supported machine # [ 9.005908] kvm_amd: Nested Virtualization enabled machine # [ 9.009121] kvm_amd: Nested Paging enabled machine # [ 9.009527] kvm_amd: LBR virtualization supported machine # [ 9.009995] kvm_amd: Virtual VMLOAD VMSAVE supported machine # [ 9.011649] kvm_amd: Virtual GIF supported machine # [ 9.012042] kvm_amd: Virtual NMI enabled machine # [ 8.879628] systemd[623]: Finished Create User Files and Directories. machine # [ 8.882398] systemd[1]: Finished Extra networking commands.. machine # [ 8.884556] systemd[1]: Reached target Network. machine # [ 9.028208] 8021q: 802.1Q VLAN Support v1.8 machine # [ 9.028673] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 8.892415] systemd[1]: Starting Permit User Sessions... machine # [ 8.913575] systemd[623]: Listening on D-Bus User Message Bus Socket. machine # [ 8.914735] systemd[623]: Reached target Sockets. machine # [ 8.920253] systemd[623]: Reached target Basic System. machine # [ 8.920968] systemd[1]: Started User Manager for UID 1001. machine # [ 8.923308] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 8.945089] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 9.109023] EDAC MC: Ver: 3.0.0 machine # [ 8.992716] systemd[1]: Finished Permit User Sessions. machine # [ 8.997097] systemd[1]: Started Getty on tty1. machine # [ 8.998428] systemd[1]: Reached target Login Prompts. machine # [ 9.014807] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 9.015753] systemd[623]: Reached target Main User Target. machine # [ 9.016486] systemd[623]: Startup finished in 409ms. machine # [ 9.179768] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 9.191483] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 9.192357] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 9.194322] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 9.195168] cfg80211: failed to load regulatory.db machine # [ 9.222570] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 9.081565] dhcpcd[689]: eth0: waiting for carrier machine # [ 9.082471] dhcpcd[689]: eth0: carrier acquired machine # [ 9.087846] dhcpcd[689]: DUID 00:01:00:01:32:3b:72:ff:52:54:00:12:34:56 machine # [ 9.089141] dhcpcd[689]: eth0: IAID 00:12:34:56 machine # [ 9.089884] dhcpcd[689]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 9.131441] systemd-vconsole-setup[632]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 9.134614] systemd[1]: Finished Virtual Console Setup. machine # [ 10.239971] dhcpcd[689]: eth0: soliciting a DHCP lease machine # [ 10.399545] NET: Registered PF_PACKET protocol family machine # [ 10.263958] dhcpcd[689]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 10.266204] dhcpcd[689]: eth0: probing address 10.0.2.15/24 machine # [ 11.033910] dhcpcd[689]: eth0: soliciting an IPv6 router machine # [ 11.036533] dhcpcd[689]: eth0: Router Advertisement from fe80::2 machine # [ 11.038936] dhcpcd[689]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 11.041292] dhcpcd[689]: eth0: adding route to fec0::/64 machine # [ 11.043235] dhcpcd[689]: eth0: adding default route via fe80::2 machine # [ 15.386197] dhcpcd[689]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 15.387781] dhcpcd[689]: eth0: adding route to 10.0.2.0/24 machine # [ 15.388757] dhcpcd[689]: eth0: adding default route via 10.0.2.2 machine # [ 15.445471] systemd[1]: Started DHCP Client. machine # [ 15.447541] systemd[1]: Reached target Multi-User System. machine # [ 15.448620] systemd[1]: Startup finished in 829ms (kernel) + 4.428s (initrd) + 10.189s (userspace) = 15.447s. machine: (finished: waiting for unit multi-user.target, in 16.46 seconds) machine: must succeed: /nix/store/yidcpw0fsqryxdbi00sgaskaw7wb9pps-stderr-runner /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test machine # Checking switch inhibitors... done machine # [ 15.765098] nixos[841]: switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test machine # [ 15.767281] systemd[1]: Stopped target Remote File Systems. machine # [ 15.768757] systemd[1]: Stopped target Local File Systems. machine # activating the configuration... machine # setting up /etc... machine # [ 15.984989] systemd[1]: Reload requested from client PID 841 ('.switch-to-conf') (unit backdoor.service)... machine # [ 15.986718] systemd[1]: Reloading... machine # [ 16.210935] systemd[1]: Reloading finished in 223 ms. machine # reloading user units for usertest... machine # [ 16.240933] systemd[623]: Created slice User Core Session Slice. machine # [ 16.243135] systemd[623]: Starting D-Bus User Message Bus... machine # [ 16.264719] dbus-broker-launch[911]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/abzvksrj3003zwg0iwcfzxlrk3ywxf85-system-path/share/dbus-1/services/org.freedesktop.import1.service' machine # [ 16.267364] dbus-broker-launch[911]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/abzvksrj3003zwg0iwcfzxlrk3ywxf85-system-path/share/dbus-1/services/org.freedesktop.machine1.service' machine # [ 16.269964] dbus-broker-launch[911]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/abzvksrj3003zwg0iwcfzxlrk3ywxf85-system-path/share/dbus-1/services/org.freedesktop.portable1.service' machine # [ 16.272478] dbus-broker-launch[911]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/abzvksrj3003zwg0iwcfzxlrk3ywxf85-system-path/share/dbus-1/services/org.freedesktop.systemd1.service' machine # [ 16.275068] dbus-broker-launch[911]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored machine # [ 16.276618] dbus-broker-launch[911]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored machine # [ 16.278509] systemd[623]: Started D-Bus User Message Bus. machine # [ 16.281841] dbus-broker-launch[911]: Ready machine # [ 16.319950] systemd[623]: Reexecution requested from client PID 910 ('switch-to-confi')... machine # [ 16.321203] systemd[623]: Reexecuting. machine # [ 16.337158] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 16.408423] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 16.413368] systemd[623]: Reload requested from client PID 910 ('switch-to-confi')... machine # [ 16.414792] systemd[623]: Reloading... machine # [ 16.485433] systemd[623]: Reloading finished in 69 ms. machine # [ 16.490853] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 16.498150] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 16.499051] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 16.501195] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 16.519919] systemd[623]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 16.529362] systemd[1]: Starting Re-setup tmpfiles on a system that is already running.... machine # [ 16.596884] systemd[1]: Finished Re-setup tmpfiles on a system that is already running.. machine # [ 16.598332] systemd[1]: Reached target Reactivate sysinit units. machine # [ 16.600936] systemd[1]: Reached target Remote File Systems. machine # [ 16.605449] systemd[1]: Starting linger-users.service... machine # [ 16.612068] systemd[1]: Reached target Local File Systems. machine # [ 16.612831] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 16.617827] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 16.619116] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 16.620971] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 16.624129] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 16.625896] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 16.630294] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 16.631653] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 16.633172] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 16.666684] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 16.668435] systemd[1]: Finished linger-users.service. machine # [ 16.763680] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 16.765301] systemd[1]: Finished Create SUID/SGID Wrappers. machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service machine # [ 17.026499] nixos[841]: finished switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test machine: (finished: must succeed: /nix/store/yidcpw0fsqryxdbi00sgaskaw7wb9pps-stderr-runner /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test, in 1.46 seconds) machine: must succeed: rm /etc/NIXOS machine: (finished: must succeed: rm /etc/NIXOS, in 0.03 seconds) machine: must succeed: /nix/store/yidcpw0fsqryxdbi00sgaskaw7wb9pps-stderr-runner /nix/store/by0sa33w3cx0cj0q3h4a6pb6ma3d3rkn-nixos-system-other-test/bin/switch-to-configuration test machine # this should succeed (config: /nix/store/by0sa33w3cx0cj0q3h4a6pb6ma3d3rkn-nixos-system-other-test, action: test) machine # Checking switch inhibitors... done machine # [ 17.283050] nixos[988]: switching to system configuration /nix/store/by0sa33w3cx0cj0q3h4a6pb6ma3d3rkn-nixos-system-other-test machine # activating the configuration... machine # [ 17.286478] systemd[1]: Stopped target Local File Systems. machine # [ 17.287352] systemd[1]: Stopped target Remote File Systems. machine # removing user ‘usertest’ machine # setting up /etc... machine # [ 17.507872] systemd[1]: Reload requested from client PID 988 ('.switch-to-conf') (unit backdoor.service)... machine # [ 17.509606] systemd[1]: Reloading... machine # [ 17.796978] systemd[1]: Reloading finished in 286 ms. machine # reloading user units for usertest... machine # [ 17.864076] systemd[623]: Reexecution requested from client PID 1059 ('switch-to-confi')... machine # [ 17.865368] systemd[623]: Reexecuting. machine # [ 17.883972] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 17.957495] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 17.962568] systemd[623]: Reload requested from client PID 1059 ('switch-to-confi')... machine # [ 17.963921] systemd[623]: Reloading... machine # [ 18.040045] systemd[623]: Reloading finished in 74 ms. machine # [ 18.045830] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 18.052609] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 18.054098] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 18.056060] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 18.075931] systemd[623]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 18.079327] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 18.080652] systemd[1]: Stopping Reactivate sysinit units... machine # [ 18.081574] systemd[1]: Reached target Reactivate sysinit units. machine # reloading the following units: dbus-broker.service machine # [ 18.084623] dbus-broker-launch[501]: Caught SIGHUP, trigger reload. machine # [ 18.086119] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 18.090819] dbus-broker-launch[501]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 18.092556] dbus-broker-launch[501]: NSS returned no entry for 'systemd-timesync' machine # [ 18.093467] dbus-broker-launch[501]: Invalid user-name in /nix/store/19aiqw6rbcrmy1mdrgsaq7p6p1hxm12h-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 18.103894] systemd[1]: Reloaded D-Bus System Message Bus. machine # restarting the following units: network-addresses-eth1.service machine # [ 18.108543] systemd[1]: Stopping Address configuration of eth1... machine # [ 18.129710] network-addresses-eth1-pre-stop[1071]: deleting address 192.168.1.1/24... done machine # [ 18.134661] network-addresses-eth1-pre-stop[1071]: deleting address 2001:db8:1::1/64... done machine # [ 18.139576] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 18.141132] systemd[1]: Stopped Address configuration of eth1. machine # [ 18.144379] systemd[1]: Starting Address configuration of eth1... machine # [ 18.171376] network-addresses-eth1-start[1076]: adding address 192.168.1.2/24... done machine # [ 18.176698] network-addresses-eth1-start[1076]: adding address 2001:db8:1::2/64... done machine # [ 18.183613] systemd[1]: Finished Address configuration of eth1. machine # [ 18.185823] systemd[1]: Reached target Local File Systems. machine # [ 18.190418] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 18.194096] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 18.195854] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 18.197035] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 18.199082] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 18.200477] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 18.204117] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 18.209528] systemd[1]: Reached target Remote File Systems. machine # [ 18.213899] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 18.215589] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 18.352512] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 18.354099] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 18.357633] systemd[1]: Starting linger-users.service... machine # [ 18.376516] linger-users-start[1148]: id: ‘usertest’: no such user machine # [ 18.378066] linger-users-start[1146]: Removing linger for missing user usertest machine # [ 18.382600] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 18.383968] systemd[1]: Finished linger-users.service. machine # [ 18.641927] nixos[988]: finished switching to system configuration /nix/store/by0sa33w3cx0cj0q3h4a6pb6ma3d3rkn-nixos-system-other-test machine: (finished: must succeed: /nix/store/yidcpw0fsqryxdbi00sgaskaw7wb9pps-stderr-runner /nix/store/by0sa33w3cx0cj0q3h4a6pb6ma3d3rkn-nixos-system-other-test/bin/switch-to-configuration test, in 1.60 seconds) machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 18.869095] nixos[1154]: switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test machine # [ 18.871421] systemd[1]: Stopped target Remote File Systems. machine # [ 18.873189] systemd[1]: Stopped target Local File Systems. machine # [ 19.080900] systemd[1]: Reload requested from client PID 1154 ('.switch-to-conf') (unit backdoor.service)... machine # [ 19.082979] systemd[1]: Reloading... machine # [ 19.319977] systemd[1]: Reloading finished in 235 ms. machine # [ 19.379366] systemd[623]: Reexecution requested from client PID 1224 ('switch-to-confi')... machine # [ 19.381763] systemd[623]: Reexecuting. machine # [ 19.401230] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 19.473441] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 19.478852] systemd[623]: Reload requested from client PID 1224 ('switch-to-confi')... machine # [ 19.480614] systemd[623]: Reloading... machine # [ 19.550779] systemd[623]: Reloading finished in 69 ms. machine # [ 19.555813] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 19.562107] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 19.562968] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 19.564968] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 19.583809] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 19.586790] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 19.587982] systemd[1]: Stopping Reactivate sysinit units... machine # [ 19.589241] systemd[1]: Reached target Reactivate sysinit units. machine # [ 19.591790] dbus-broker-launch[501]: Caught SIGHUP, trigger reload. machine # [ 19.593389] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 19.596743] dbus-broker-launch[501]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 19.598459] dbus-broker-launch[501]: NSS returned no entry for 'systemd-timesync' machine # [ 19.599440] dbus-broker-launch[501]: Invalid user-name in /nix/store/abzvksrj3003zwg0iwcfzxlrk3ywxf85-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 19.606553] systemd[1]: Reloaded D-Bus System Message Bus. machine # [ 19.610526] systemd[1]: Stopping Address configuration of eth1... machine # [ 19.629967] network-addresses-eth1-pre-stop[1236]: deleting address 192.168.1.2/24... done machine # [ 19.634894] network-addresses-eth1-pre-stop[1236]: deleting address 2001:db8:1::2/64... done machine # [ 19.639798] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 19.641377] systemd[1]: Stopped Address configuration of eth1. machine # [ 19.644757] systemd[1]: Starting Address configuration of eth1... machine # [ 19.672498] network-addresses-eth1-start[1241]: adding address 192.168.1.1/24... done machine # [ 19.677743] network-addresses-eth1-start[1241]: adding address 2001:db8:1::1/64... done machine # [ 19.684699] systemd[1]: Finished Address configuration of eth1. machine # [ 19.689289] systemd[1]: Reached target Remote File Systems. machine # [ 19.691953] systemd[1]: Starting linger-users.service... machine # [ 19.699286] systemd[1]: Reached target Local File Systems. machine # [ 19.700079] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 19.701244] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 19.705583] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 19.711307] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 19.712111] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 19.713449] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 19.716475] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 19.719117] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 19.720286] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 19.743136] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 19.744652] systemd[1]: Finished linger-users.service. machine # [ 19.856770] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 19.858511] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 20.115797] nixos[1154]: finished switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.46 seconds) subtest: user services machine: waiting for unit user@1001.service machine: (finished: waiting for unit user@1001.service, in 0.06 seconds) machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 20.375860] nixos[1314]: switching to system configuration /nix/store/qyjw77mc0hv6f7jsza7mnq5pada13yvq-nixos-system-machine-test machine # [ 20.378492] systemd[1]: Stopped target Remote File Systems. machine # [ 20.379822] systemd[1]: Stopped target Local File Systems. machine # [ 20.599460] systemd[1]: Reload requested from client PID 1314 ('.switch-to-conf') (unit backdoor.service)... machine # [ 20.601117] systemd[1]: Reloading... machine # [ 20.828382] systemd[1]: Reloading finished in 226 ms. machine # [ 20.892922] systemd[623]: Reexecution requested from client PID 1383 ('switch-to-confi')... machine # [ 20.894774] systemd[623]: Reexecuting. machine # [ 20.915062] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 20.989979] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 20.994992] systemd[623]: Reload requested from client PID 1383 ('switch-to-confi')... machine # [ 20.996712] systemd[623]: Reloading... machine # [ 21.068649] systemd[623]: Reloading finished in 70 ms. machine # [ 21.073815] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 21.080258] systemd[623]: Starting usertest.service... machine # [ 21.096827] systemd[623]: Finished usertest.service. machine # [ 21.099367] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 21.100233] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 21.102241] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 21.121398] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 21.124363] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 21.125629] systemd[1]: Stopping Reactivate sysinit units... machine # [ 21.126567] systemd[1]: Reached target Reactivate sysinit units. machine # [ 21.129608] systemd[1]: Reached target Remote File Systems. machine # [ 21.133416] systemd[1]: Starting linger-users.service... machine # [ 21.135582] systemd[1]: Reached target Local File Systems. machine # [ 21.140980] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 21.142340] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 21.147430] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 21.152127] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 21.152916] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 21.155683] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 21.157586] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 21.160101] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 21.161291] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 21.193909] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 21.195528] systemd[1]: Finished linger-users.service. machine # [ 21.303433] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 21.305109] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 21.561533] nixos[1314]: finished switching to system configuration /nix/store/qyjw77mc0hv6f7jsza7mnq5pada13yvq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.38 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 21.612738] sudo[1453]: 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 # [ 21.626789] sudo[1453]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 21.637895] sudo[1453]: 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/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 21.814156] nixos[1462]: switching to system configuration /nix/store/qyjw77mc0hv6f7jsza7mnq5pada13yvq-nixos-system-machine-test machine # [ 21.816404] systemd[1]: Stopped target Remote File Systems. machine # [ 21.818462] systemd[1]: Stopped target Local File Systems. machine # [ 22.052987] systemd[1]: Reload requested from client PID 1462 ('.switch-to-conf') (unit backdoor.service)... machine # [ 22.054695] systemd[1]: Reloading... machine # [ 22.282061] systemd[1]: Reloading finished in 223 ms. machine # [ 22.344509] systemd[623]: Reexecution requested from client PID 1532 ('switch-to-confi')... machine # [ 22.345946] systemd[623]: Reexecuting. machine # [ 22.364979] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 22.438350] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 22.443512] systemd[623]: Reload requested from client PID 1532 ('switch-to-confi')... machine # [ 22.444902] systemd[623]: Reloading... machine # [ 22.517173] systemd[623]: Reloading finished in 70 ms. machine # [ 22.522816] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 22.528939] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 22.529920] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 22.532432] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 22.550778] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 22.553660] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 22.554921] systemd[1]: Stopping Reactivate sysinit units... machine # [ 22.555847] systemd[1]: Reached target Reactivate sysinit units. machine # [ 22.557307] systemd[1]: Reached target Local File Systems. machine # [ 22.560322] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.561945] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 22.565116] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 22.567811] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 22.569272] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 22.571134] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 22.573071] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 22.574523] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.575692] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.584258] systemd[1]: Reached target Remote File Systems. machine # [ 22.705094] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 22.706659] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 22.710606] systemd[1]: Starting linger-users.service... machine # [ 22.736219] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 22.737655] systemd[1]: Finished linger-users.service. machine # [ 22.994801] nixos[1462]: finished switching to system configuration /nix/store/qyjw77mc0hv6f7jsza7mnq5pada13yvq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.36 seconds) machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1 machine # [ 23.205116] nixos[1603]: switching to system configuration /nix/store/2vps2kbm93fsps7wg2g2cg3zr98ga33i-nixos-system-machine-test machine # [ 23.207445] systemd[1]: Stopped target Local File Systems. machine # [ 23.209162] systemd[1]: Stopped target Remote File Systems. machine # [ 23.441588] systemd[1]: Reload requested from client PID 1603 ('.switch-to-conf') (unit backdoor.service)... machine # [ 23.443436] systemd[1]: Reloading... machine # [ 23.670552] systemd[1]: Reloading finished in 225 ms. machine # [ 23.741877] systemd[623]: Stopped usertest.service. machine # [ 23.743440] systemd[623]: Reexecution requested from client PID 1672 ('switch-to-confi')... machine # [ 23.745927] systemd[623]: Reexecuting. machine # [ 23.765092] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.841442] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.846821] systemd[623]: Reload requested from client PID 1672 ('switch-to-confi')... machine # [ 23.848173] systemd[623]: Reloading... machine # [ 23.920419] systemd[623]: Reloading finished in 71 ms. machine # [ 23.925827] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.932564] systemd[623]: Starting usertest.service... machine # [ 23.947800] systemd[623]: Finished usertest.service. machine # [ 23.950134] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 23.951246] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 23.953575] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 23.971309] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 23.974069] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 23.975582] systemd[1]: Stopping Reactivate sysinit units... machine # [ 23.976698] systemd[1]: Reached target Reactivate sysinit units. machine # [ 23.979346] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 23.981196] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 23.983361] systemd[1]: Reached target Local File Systems. machine # [ 23.986359] systemd[1]: Reached target Remote File Systems. machine # [ 23.990552] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 23.995097] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 23.995990] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 23.997946] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 24.000184] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 24.001812] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.005679] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.136563] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 24.140509] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 24.142117] systemd[1]: Starting linger-users.service... machine # [ 24.169061] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 24.171673] systemd[1]: Finished linger-users.service. machine # [ 24.427930] nixos[1603]: finished switching to system configuration /nix/store/2vps2kbm93fsps7wg2g2cg3zr98ga33i-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 1.43 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 24.474707] sudo[1742]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 24.488805] sudo[1742]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 24.499865] sudo[1742]: 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/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 24.677049] nixos[1751]: switching to system configuration /nix/store/npabl8y25vjdyx718glf6bsylj955mg4-nixos-system-machine-test machine # [ 24.679657] systemd[1]: Stopped target Remote File Systems. machine # [ 24.681289] systemd[1]: Stopped target Local File Systems. machine # [ 24.903858] systemd[1]: Reload requested from client PID 1751 ('.switch-to-conf') (unit backdoor.service)... machine # [ 24.905470] systemd[1]: Reloading... machine # [ 25.144091] systemd[1]: Reloading finished in 237 ms. machine # [ 25.224276] systemd[623]: Reexecution requested from client PID 1820 ('switch-to-confi')... machine # [ 25.226132] systemd[623]: Reexecuting. machine # [ 25.245980] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 25.321784] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 25.327396] systemd[623]: Reload requested from client PID 1820 ('switch-to-confi')... machine # [ 25.330129] systemd[623]: Reloading... machine # [ 25.402859] systemd[623]: Reloading finished in 73 ms. machine # [ 25.407832] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 25.411594] systemd[623]: Stopped usertest.service. machine # [ 25.412397] systemd[623]: Stopping usertest.service... machine # [ 25.414584] systemd[623]: Starting usertest.service... machine # [ 25.428741] systemd[623]: Finished usertest.service. machine # [ 25.433301] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 25.435064] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 25.436692] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 25.455519] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 25.458400] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 25.459632] systemd[1]: Stopping Reactivate sysinit units... machine # [ 25.460619] systemd[1]: Reached target Reactivate sysinit units. machine # [ 25.463193] systemd[1]: Reached target Remote File Systems. machine # [ 25.465153] systemd[1]: Starting linger-users.service... machine # [ 25.470383] systemd[1]: Reached target Local File Systems. machine # [ 25.474297] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 25.476094] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 25.478741] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 25.483083] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 25.483874] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 25.486446] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 25.488945] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 25.491227] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 25.493131] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 25.527844] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 25.528993] systemd[1]: Finished linger-users.service. machine # [ 25.636735] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 25.638518] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 25.896215] nixos[1751]: finished switching to system configuration /nix/store/npabl8y25vjdyx718glf6bsylj955mg4-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1, in 1.40 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 25.941755] sudo[1891]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 25.957907] sudo[1891]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 25.970468] sudo[1891]: 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/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1 machine # [ 26.148474] nixos[1900]: switching to system configuration /nix/store/09vl9xafd5p28ikjwy3ka1rkczkwid7n-nixos-system-machine-test machine # [ 26.150657] systemd[1]: Stopped target Remote File Systems. machine # [ 26.152477] systemd[1]: Stopped target Local File Systems. machine # [ 26.371250] systemd[1]: Reload requested from client PID 1900 ('.switch-to-conf') (unit backdoor.service)... machine # [ 26.372849] systemd[1]: Reloading... machine # [ 26.595753] systemd[1]: Reloading finished in 221 ms. machine # [ 26.672911] systemd[623]: Reexecution requested from client PID 1969 ('switch-to-confi')... machine # [ 26.675810] systemd[623]: Reexecuting. machine # [ 26.696125] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 26.775192] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 26.780838] systemd[623]: Reload requested from client PID 1969 ('switch-to-confi')... machine # [ 26.782730] systemd[623]: Reloading... machine # [ 26.849654] systemd[623]: Reloading finished in 66 ms. machine # [ 26.854836] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 26.860137] systemd[623]: Reloading usertest.service... machine # [ 26.875492] systemd[623]: Reloaded usertest.service. machine # [ 26.881086] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 26.881935] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 26.883460] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 26.902940] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 26.905957] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 26.907373] systemd[1]: Stopping Reactivate sysinit units... machine # [ 26.908303] systemd[1]: Reached target Reactivate sysinit units. machine # [ 26.909687] systemd[1]: Reached target Remote File Systems. machine # [ 26.913447] systemd[1]: Starting linger-users.service... machine # [ 26.919045] systemd[1]: Reached target Local File Systems. machine # [ 26.926068] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 26.931338] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 26.932244] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 26.934369] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 26.937339] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 26.939781] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.942270] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.943399] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.944578] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 26.978094] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 26.979843] systemd[1]: Finished linger-users.service. machine # [ 27.077293] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 27.078916] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 27.336723] nixos[1900]: finished switching to system configuration /nix/store/09vl9xafd5p28ikjwy3ka1rkczkwid7n-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1, in 1.37 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 27.384879] sudo[2039]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 27.400526] sudo[2039]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 27.410379] sudo[2039]: 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/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 27.589434] nixos[2048]: switching to system configuration /nix/store/qyjw77mc0hv6f7jsza7mnq5pada13yvq-nixos-system-machine-test machine # [ 27.591656] systemd[1]: Stopped target Remote File Systems. machine # [ 27.593242] systemd[1]: Stopped target Local File Systems. machine # [ 27.805605] systemd[1]: Reload requested from client PID 2048 ('.switch-to-conf') (unit backdoor.service)... machine # [ 27.807145] systemd[1]: Reloading... machine # [ 28.042631] systemd[1]: Reloading finished in 234 ms. machine # [ 28.108465] systemd[623]: Stopped usertest.service. machine # [ 28.109782] systemd[623]: Reexecution requested from client PID 2117 ('switch-to-confi')... machine # [ 28.111657] systemd[623]: Reexecuting. machine # [ 28.131122] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 28.206690] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 28.211880] systemd[623]: Reload requested from client PID 2117 ('switch-to-confi')... machine # [ 28.213627] systemd[623]: Reloading... machine # [ 28.309485] systemd[623]: Reloading finished in 94 ms. machine # [ 28.314837] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 28.322028] systemd[623]: Starting usertest.service... machine # [ 28.337160] systemd[623]: Finished usertest.service. machine # [ 28.340060] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 28.340947] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 28.342614] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 28.362083] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 28.365173] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 28.366087] systemd[1]: Stopping Reactivate sysinit units... machine # [ 28.366982] systemd[1]: Reached target Reactivate sysinit units. machine # [ 28.368765] systemd[1]: Reached target Remote File Systems. machine # [ 28.371873] systemd[1]: Reached target Local File Systems. machine # [ 28.374171] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 28.377226] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 28.378565] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 28.379709] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 28.381633] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 28.383032] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 28.384154] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 28.391764] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 28.392953] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 28.521531] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 28.523185] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 28.526830] systemd[1]: Starting linger-users.service... machine # [ 28.554884] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 28.556927] systemd[1]: Finished linger-users.service. machine # [ 28.813507] nixos[2048]: finished switching to system configuration /nix/store/qyjw77mc0hv6f7jsza7mnq5pada13yvq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.41 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 28.862779] sudo[2188]: 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.877066] sudo[2188]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 28.888582] sudo[2188]: 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/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1 machine # [ 29.071343] nixos[2197]: switching to system configuration /nix/store/agid4d0pd3ijbf4z9kj6dswg7pal0n7h-nixos-system-machine-test machine # [ 29.073703] systemd[1]: Stopped target Local File Systems. machine # [ 29.075370] systemd[1]: Stopped target Remote File Systems. machine # [ 29.315787] systemd[1]: Reload requested from client PID 2197 ('.switch-to-conf') (unit backdoor.service)... machine # [ 29.317409] systemd[1]: Reloading... machine # [ 29.524043] systemd[1]: Reloading finished in 204 ms. machine # [ 29.589355] systemd[623]: Reexecution requested from client PID 2266 ('switch-to-confi')... machine # [ 29.591941] systemd[623]: Reexecuting. machine # [ 29.611542] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.697710] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.703614] systemd[623]: Reload requested from client PID 2266 ('switch-to-confi')... machine # [ 29.704924] systemd[623]: Reloading... machine # [ 29.778747] systemd[623]: Reloading finished in 72 ms. machine # [ 29.784945] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.790203] systemd[623]: Reloading usertest.service... machine # [ 29.805406] systemd[623]: Reloaded usertest.service. machine # [ 29.809864] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 29.810865] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 29.813146] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 29.830758] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 29.833721] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 29.834963] systemd[1]: Stopping Reactivate sysinit units... machine # [ 29.835979] systemd[1]: Reached target Reactivate sysinit units. machine # [ 29.837688] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 29.839232] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 29.841469] systemd[1]: Reached target Local File Systems. machine # [ 29.845116] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 29.848208] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 29.849905] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 29.851068] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 29.852976] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 29.854385] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 29.855541] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 29.862812] systemd[1]: Reached target Remote File Systems. machine # [ 29.984202] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 29.986069] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 29.989567] systemd[1]: Starting linger-users.service... machine # [ 30.017280] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 30.018712] systemd[1]: Finished linger-users.service. machine # [ 30.275695] nixos[2197]: finished switching to system configuration /nix/store/agid4d0pd3ijbf4z9kj6dswg7pal0n7h-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 1.39 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 30.324064] sudo[2336]: 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 # [ 30.339407] sudo[2336]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 30.346408] sudo[2336]: 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 fail: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1 machine # [ 30.511742] nixos[2345]: switching to system configuration /nix/store/jp42z24brpvgsw866j7n27f78ib74l1q-nixos-system-machine-test machine # [ 30.514025] systemd[1]: Stopped target Remote File Systems. machine # [ 30.515704] systemd[1]: Stopped target Local File Systems. machine # [ 30.743364] systemd[1]: Reload requested from client PID 2345 ('.switch-to-conf') (unit backdoor.service)... machine # [ 30.744970] systemd[1]: Reloading... machine # [ 30.974100] systemd[1]: Reloading finished in 227 ms. machine # [ 31.045557] systemd[623]: Stopped usertest.service. machine # [ 31.048089] systemd[623]: Reexecution requested from client PID 2414 ('switch-to-confi')... machine # [ 31.049383] systemd[623]: Reexecuting. machine # [ 31.072175] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 31.151699] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 31.157654] systemd[623]: Reload requested from client PID 2414 ('switch-to-confi')... machine # [ 31.158923] systemd[623]: Reloading... machine # [ 31.232255] systemd[623]: Reloading finished in 72 ms. machine # [ 31.237835] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 31.243852] systemd[623]: Starting usertest.service... machine # [ 31.261457] systemd[623]: usertest.service: Main process exited, code=exited, status=1/FAILURE machine # [ 31.262752] systemd[623]: usertest.service: Failed with result 'exit-code'. machine # [ 31.264363] systemd[623]: Failed to start usertest.service. machine # [ 31.266766] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 31.268244] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 31.270246] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 31.288693] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 31.291761] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 31.292962] systemd[1]: Stopping Reactivate sysinit units... machine # [ 31.293947] systemd[1]: Reached target Reactivate sysinit units. machine # [ 31.295928] systemd[1]: Reached target Remote File Systems. machine # [ 31.297624] systemd[1]: Reached target Local File Systems. machine # [ 31.300458] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 31.302106] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 31.307221] systemd[1]: Starting linger-users.service... machine # [ 31.315870] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 31.318760] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 31.320190] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 31.321293] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 31.323233] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 31.324624] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 31.325770] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 31.365217] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 31.366829] systemd[1]: Finished linger-users.service. machine # [ 31.501497] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 31.505670] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 31.761735] nixos[2345]: switching to system configuration /nix/store/jp42z24brpvgsw866j7n27f78ib74l1q-nixos-system-machine-test failed (status 4) machine: (finished: must fail: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 1.42 seconds) machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 31.974298] nixos[2488]: switching to system configuration /nix/store/qyjw77mc0hv6f7jsza7mnq5pada13yvq-nixos-system-machine-test machine # [ 31.976835] systemd[1]: Stopped target Local File Systems. machine # [ 31.978565] systemd[1]: Stopped target Remote File Systems. machine # [ 32.186947] systemd[1]: Reload requested from client PID 2488 ('.switch-to-conf') (unit backdoor.service)... machine # [ 32.188597] systemd[1]: Reloading... machine # [ 32.431172] systemd[1]: Reloading finished in 240 ms. machine # [ 32.495545] systemd[623]: Reexecution requested from client PID 2557 ('switch-to-confi')... machine # [ 32.497146] systemd[623]: Reexecuting. machine # [ 32.518278] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.591716] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.596903] systemd[623]: Reload requested from client PID 2557 ('switch-to-confi')... machine # [ 32.598713] systemd[623]: Reloading... machine # [ 32.675561] systemd[623]: Reloading finished in 76 ms. machine # [ 32.680858] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.687280] systemd[623]: Starting usertest.service... machine # [ 32.703165] systemd[623]: Finished usertest.service. machine # [ 32.705395] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 32.707152] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 32.708588] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 32.728205] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 32.731277] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 32.732667] systemd[1]: Stopping Reactivate sysinit units... machine # [ 32.733650] systemd[1]: Reached target Reactivate sysinit units. machine # [ 32.736366] systemd[1]: Reached target Local File Systems. machine # [ 32.740403] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 32.742096] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 32.744726] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 32.750935] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 32.751772] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 32.752904] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 32.754839] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 32.756265] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 32.757413] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 32.762052] systemd[1]: Reached target Remote File Systems. machine # [ 32.890133] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 32.893191] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 32.895454] systemd[1]: Starting linger-users.service... machine # [ 32.925504] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 32.927081] systemd[1]: Finished linger-users.service. machine # [ 33.183809] nixos[2488]: finished switching to system configuration /nix/store/qyjw77mc0hv6f7jsza7mnq5pada13yvq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.42 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 33.231554] sudo[2627]: 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.245699] sudo[2627]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 33.257059] sudo[2627]: 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/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 33.441693] nixos[2636]: switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test machine # [ 33.443923] systemd[1]: Stopped target Remote File Systems. machine # [ 33.445589] systemd[1]: Stopped target Local File Systems. machine # [ 33.643613] systemd[1]: Reload requested from client PID 2636 ('.switch-to-conf') (unit backdoor.service)... machine # [ 33.645155] systemd[1]: Reloading... machine # [ 33.878354] systemd[1]: Reloading finished in 231 ms. machine # [ 33.944501] systemd[623]: Stopped usertest.service. machine # [ 33.945812] systemd[623]: Reexecution requested from client PID 2705 ('switch-to-confi')... machine # [ 33.948244] systemd[623]: Reexecuting. machine # [ 33.969960] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.041514] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.046454] systemd[623]: Reload requested from client PID 2705 ('switch-to-confi')... machine # [ 34.047723] systemd[623]: Reloading... machine # [ 34.119370] systemd[623]: Reloading finished in 70 ms. machine # [ 34.124829] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.132108] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 34.132983] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 34.134805] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 34.154218] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 34.157053] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 34.158560] systemd[1]: Stopping Reactivate sysinit units... machine # [ 34.159482] systemd[1]: Reached target Reactivate sysinit units. machine # [ 34.161280] systemd[1]: Reached target Local File Systems. machine # [ 34.165610] systemd[1]: Reached target Remote File Systems. machine # [ 34.168629] systemd[1]: Starting linger-users.service... machine # [ 34.175068] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 34.178870] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 34.179687] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 34.181356] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 34.183248] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 34.184666] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.185801] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.190283] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.191518] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 34.228275] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 34.229885] systemd[1]: Finished linger-users.service. machine # [ 34.333474] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 34.335098] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 34.591477] nixos[2636]: finished switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.34 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 34.636544] sudo[2773]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 34.657172] sudo[2773]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 34.668903] sudo[2773]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 34.709418] sudo[2779]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 34.719348] sudo[2779]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 34.726589] sudo[2779]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds) machine: must succeed: sudo -u usertest cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 34.749095] sudo[2786]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 34.756866] sudo[2786]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 34.763137] sudo[2786]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 34.782695] sudo[2792]: 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 # [ 34.790461] sudo[2792]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 34.796124] sudo[2792]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 34.815561] sudo[2798]: 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 # [ 34.823467] sudo[2798]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 34.828651] systemd[623]: Reload requested from client PID 2803 ('systemctl')... machine # [ 34.829776] systemd[623]: Reloading... machine # [ 34.906545] systemd[623]: Reloading finished in 75 ms. machine # [ 34.911848] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.919071] sudo[2798]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 34.939727] sudo[2808]: 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 # [ 34.947261] sudo[2808]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 34.956683] systemd[623]: Starting migrated.service... machine # [ 34.972560] systemd[623]: Finished migrated.service. machine # [ 34.975303] sudo[2808]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 34.995162] sudo[2816]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 35.002465] sudo[2816]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 35.009202] sudo[2816]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 35.028825] sudo[2822]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 35.037540] sudo[2822]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 35.043122] sudo[2822]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 35.062919] sudo[2828]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 35.070803] sudo[2828]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 35.082781] sudo[2828]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds) machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1 machine # [ 35.267629] nixos[2837]: switching to system configuration /nix/store/bkgcxsnl7zqy0k8d2x1px2izidbzbc7l-nixos-system-machine-test machine # [ 35.270056] systemd[1]: Stopped target Local File Systems. machine # [ 35.271678] systemd[1]: Stopped target Remote File Systems. machine # [ 35.500611] systemd[1]: Reload requested from client PID 2837 ('.switch-to-conf') (unit backdoor.service)... machine # [ 35.502145] systemd[1]: Reloading... machine # [ 35.729218] systemd[1]: Reloading finished in 225 ms. machine # [ 35.795079] systemd[623]: Reexecution requested from client PID 2906 ('switch-to-confi')... machine # [ 35.797677] systemd[623]: Reexecuting. machine # [ 35.817961] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.894191] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.899286] systemd[623]: Reload requested from client PID 2906 ('switch-to-confi')... machine # [ 35.900738] systemd[623]: Reloading... machine # [ 35.972333] systemd[623]: Reloading finished in 70 ms. machine # [ 35.976847] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.983151] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 35.984183] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 35.986680] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 36.023432] systemd[623]: Reload requested from client PID 2918 ('systemctl') (unit nixos-activation.service)... machine # [ 36.025715] systemd[623]: Reloading... machine # [ 36.099738] systemd[623]: Reloading finished in 74 ms. machine # [ 36.105026] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.115862] systemd[623]: Stopped migrated.service. machine # [ 36.124580] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 36.126494] systemd[623]: Reload requested from client PID 2906 ('switch-to-confi')... machine # [ 36.127989] systemd[623]: Reloading... machine # [ 36.198842] systemd[623]: Reloading finished in 69 ms. machine # [ 36.204850] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.211326] systemd[623]: Starting migrated.service... machine # [ 36.228963] systemd[623]: Finished migrated.service. machine # [ 36.231891] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 36.233166] systemd[1]: Stopping Reactivate sysinit units... machine # [ 36.233939] systemd[1]: Reached target Reactivate sysinit units. machine # [ 36.236314] systemd[1]: Reached target Remote File Systems. machine # [ 36.243058] systemd[1]: Starting linger-users.service... machine # [ 36.248367] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.249654] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 36.253860] systemd[1]: Reached target Local File Systems. machine # [ 36.258067] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 36.261238] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 36.263335] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 36.264993] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 36.266927] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 36.268309] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.269509] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.300883] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 36.303272] systemd[1]: Finished linger-users.service. machine # [ 36.410153] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 36.411806] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 36.669427] nixos[2837]: finished switching to system configuration /nix/store/bkgcxsnl7zqy0k8d2x1px2izidbzbc7l-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 1.59 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 36.716750] sudo[2988]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 36.732819] sudo[2988]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 36.744396] sudo[2988]: 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 # [ 36.763657] sudo[2994]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 36.771305] sudo[2994]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 36.783540] sudo[2994]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 36.802894] sudo[3000]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 36.810280] sudo[3000]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 36.815840] sudo[3000]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 36.995275] nixos[3009]: switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test machine # [ 36.999191] systemd[1]: Stopped target Local File Systems. machine # [ 37.000236] systemd[1]: Stopped target Remote File Systems. machine # [ 37.206259] systemd[1]: Reload requested from client PID 3009 ('.switch-to-conf') (unit backdoor.service)... machine # [ 37.207902] systemd[1]: Reloading... machine # [ 37.440981] systemd[1]: Reloading finished in 231 ms. machine # [ 37.501883] systemd[623]: Stopped migrated.service. machine # [ 37.503077] systemd[623]: Reexecution requested from client PID 3078 ('switch-to-confi')... machine # [ 37.504830] systemd[623]: Reexecuting. machine # [ 37.526045] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.603265] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.608697] systemd[623]: Reload requested from client PID 3078 ('switch-to-confi')... machine # [ 37.611053] systemd[623]: Reloading... machine # [ 37.684088] systemd[623]: Reloading finished in 73 ms. machine # [ 37.690071] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.696617] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 37.698067] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 37.700291] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 37.719245] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 37.722062] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 37.723567] systemd[1]: Stopping Reactivate sysinit units... machine # [ 37.724604] systemd[1]: Reached target Reactivate sysinit units. machine # [ 37.726245] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.727961] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 37.730532] systemd[1]: Reached target Local File Systems. machine # [ 37.735989] systemd[1]: Reached target Remote File Systems. machine # [ 37.738632] systemd[1]: Starting linger-users.service... machine # [ 37.747223] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 37.750297] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 37.751515] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 37.753909] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 37.757098] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 37.758699] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.759987] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.792982] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 37.794531] systemd[1]: Finished linger-users.service. machine # [ 37.942093] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 37.944239] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 38.200580] nixos[3009]: finished switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.39 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 38.245536] sudo[3147]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 38.260414] sudo[3147]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 38.269176] sudo[3147]: 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 # [ 38.289524] sudo[3153]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 38.296935] sudo[3153]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 38.302480] sudo[3153]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.03 seconds) machine: must succeed: sudo -u usertest cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 38.322360] sudo[3159]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 38.330478] sudo[3159]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 38.335815] sudo[3159]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 38.355421] sudo[3165]: 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 # [ 38.362897] sudo[3165]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 38.368412] sudo[3165]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 38.387489] sudo[3171]: 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 # [ 38.394675] sudo[3171]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 38.400347] systemd[623]: Reload requested from client PID 3176 ('systemctl')... machine # [ 38.401514] systemd[623]: Reloading... machine # [ 38.475196] systemd[623]: Reloading finished in 72 ms. machine # [ 38.480868] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 38.487676] sudo[3171]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 38.508312] sudo[3181]: 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 # [ 38.515814] sudo[3181]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 38.524910] systemd[623]: Starting migrated.service... machine # [ 38.539961] systemd[623]: Finished migrated.service. machine # [ 38.542787] sudo[3181]: 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.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 38.562318] sudo[3189]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 38.570391] sudo[3189]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 38.576712] 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 is-active migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 38.596284] sudo[3195]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 38.603548] sudo[3195]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 38.609132] sudo[3195]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 38.628815] sudo[3201]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 38.636359] sudo[3201]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 38.649649] sudo[3201]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds) machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1 machine # [ 38.827503] nixos[3210]: switching to system configuration /nix/store/1xzhpa0nhjdshfdpqxxvbxc7l2cmdwp7-nixos-system-machine-test machine # [ 38.829914] systemd[1]: Stopped target Local File Systems. machine # [ 38.831787] systemd[1]: Stopped target Remote File Systems. machine # [ 39.061827] systemd[1]: Reload requested from client PID 3210 ('.switch-to-conf') (unit backdoor.service)... machine # [ 39.063457] systemd[1]: Reloading... machine # [ 39.308955] systemd[1]: Reloading finished in 244 ms. machine # [ 39.375869] systemd[623]: Reexecution requested from client PID 3279 ('switch-to-confi')... machine # [ 39.377160] systemd[623]: Reexecuting. machine # [ 39.399427] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.478961] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.484151] systemd[623]: Reload requested from client PID 3279 ('switch-to-confi')... machine # [ 39.485770] systemd[623]: Reloading... machine # [ 39.560601] systemd[623]: Reloading finished in 73 ms. machine # [ 39.564853] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.571047] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 39.572059] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 39.574491] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 39.597736] systemd[623]: Reload requested from client PID 3291 ('systemctl') (unit nixos-activation.service)... machine # [ 39.599458] systemd[623]: Reloading... machine # [ 39.674486] systemd[623]: Reloading finished in 73 ms. machine # [ 39.680047] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.690986] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 39.693290] systemd[623]: Reload requested from client PID 3279 ('switch-to-confi')... machine # [ 39.694718] systemd[623]: Reloading... machine # [ 39.771979] systemd[623]: Reloading finished in 75 ms. machine # [ 39.777876] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.784151] systemd[623]: Stopped migrated.service. machine # [ 39.785143] systemd[623]: Stopping migrated.service... machine # [ 39.787619] systemd[623]: Starting migrated.service... machine # [ 39.803873] systemd[623]: Finished migrated.service. machine # [ 39.809600] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 39.811369] systemd[1]: Stopping Reactivate sysinit units... machine # [ 39.812486] systemd[1]: Reached target Reactivate sysinit units. machine # [ 39.816544] systemd[1]: Reached target Local File Systems. machine # [ 39.817604] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 39.819951] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 39.821327] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 39.823498] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 39.825456] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 39.826863] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.828076] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.831626] systemd[1]: Reached target Remote File Systems. machine # [ 39.841928] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.843726] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 39.966463] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 39.968332] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 39.972081] systemd[1]: Starting linger-users.service... machine # [ 39.998353] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 40.000502] systemd[1]: Finished linger-users.service. machine # [ 40.256726] nixos[3210]: finished switching to system configuration /nix/store/1xzhpa0nhjdshfdpqxxvbxc7l2cmdwp7-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 1.61 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 40.302872] sudo[3360]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 40.316725] sudo[3360]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 40.324358] sudo[3360]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 40.344150] sudo[3366]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 40.351580] sudo[3366]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 40.364257] sudo[3366]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 40.383671] sudo[3372]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 40.390976] sudo[3372]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 40.396689] sudo[3372]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 40.576865] nixos[3381]: switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test machine # [ 40.580352] systemd[1]: Stopped target Local File Systems. machine # [ 40.581348] systemd[1]: Stopped target Remote File Systems. machine # [ 40.787316] systemd[1]: Reload requested from client PID 3381 ('.switch-to-conf') (unit backdoor.service)... machine # [ 40.789184] systemd[1]: Reloading... machine # [ 41.006857] systemd[1]: Reloading finished in 215 ms. machine # [ 41.097543] systemd[623]: Stopped migrated.service. machine # [ 41.101951] systemd[623]: Reexecution requested from client PID 3450 ('switch-to-confi')... machine # [ 41.103454] systemd[623]: Reexecuting. machine # [ 41.123951] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 41.196235] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 41.201147] systemd[623]: Reload requested from client PID 3450 ('switch-to-confi')... machine # [ 41.203209] systemd[623]: Reloading... machine # [ 41.275418] systemd[623]: Reloading finished in 70 ms. machine # [ 41.280833] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 41.287302] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 41.289060] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 41.290896] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 41.309877] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 41.313058] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 41.314387] systemd[1]: Stopping Reactivate sysinit units... machine # [ 41.315527] systemd[1]: Reached target Reactivate sysinit units. machine # [ 41.319697] systemd[1]: Reached target Remote File Systems. machine # [ 41.322688] systemd[1]: Starting linger-users.service... machine # [ 41.324241] systemd[1]: Reached target Local File Systems. machine # [ 41.331374] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 41.333425] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 41.341104] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 41.345303] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 41.346846] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 41.348221] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 41.350454] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 41.352113] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 41.356072] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 41.381991] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 41.383834] systemd[1]: Finished linger-users.service. machine # [ 41.494513] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 41.497361] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 41.751437] nixos[3381]: finished switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.36 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 41.786219] sudo[3519]: 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 # [ 41.794844] sudo[3519]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 41.802553] sudo[3519]: 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.05 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 41.822284] sudo[3525]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 41.829333] sudo[3525]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 41.834713] sudo[3525]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.03 seconds) machine: must succeed: sudo -u usertest cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 41.854316] sudo[3531]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 41.862512] sudo[3531]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 41.867820] sudo[3531]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 41.886873] sudo[3537]: 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 # [ 41.894351] sudo[3537]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 41.899862] sudo[3537]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 41.918892] sudo[3543]: 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 # [ 41.926430] sudo[3543]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 41.931585] systemd[623]: Reload requested from client PID 3548 ('systemctl')... machine # [ 41.933057] systemd[623]: Reloading... machine # [ 42.008062] systemd[623]: Reloading finished in 73 ms. machine # [ 42.013909] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.020436] sudo[3543]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 42.040665] sudo[3553]: 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 # [ 42.048461] sudo[3553]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 42.058531] systemd[623]: Starting migrated.service... machine # [ 42.073236] systemd[623]: Finished migrated.service. machine # [ 42.075985] sudo[3553]: 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.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 42.095314] sudo[3561]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 42.103276] sudo[3561]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 42.112437] sudo[3561]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 42.131746] sudo[3567]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 42.139499] sudo[3567]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 42.145157] sudo[3567]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 42.165699] sudo[3573]: 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 # [ 42.173518] sudo[3573]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 42.185079] sudo[3573]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds) machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1 machine # [ 42.365379] nixos[3582]: switching to system configuration /nix/store/cwpxy45c0q1prpi6ln6czv279fsvqzpl-nixos-system-machine-test machine # [ 42.367679] systemd[1]: Stopped target Local File Systems. machine # [ 42.369308] systemd[1]: Stopped target Remote File Systems. machine # [ 42.590195] systemd[1]: Reload requested from client PID 3582 ('.switch-to-conf') (unit backdoor.service)... machine # [ 42.591810] systemd[1]: Reloading... machine # [ 42.829101] systemd[1]: Reloading finished in 235 ms. machine # [ 42.886759] systemd[623]: Reexecution requested from client PID 3651 ('switch-to-confi')... machine # [ 42.889331] systemd[623]: Reexecuting. machine # [ 42.909036] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.987900] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.993045] systemd[623]: Reload requested from client PID 3651 ('switch-to-confi')... machine # [ 42.994407] systemd[623]: Reloading... machine # [ 43.066803] systemd[623]: Reloading finished in 71 ms. machine # [ 43.071854] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.079061] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 43.079970] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 43.081605] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 43.104858] systemd[623]: Reload requested from client PID 3663 ('systemctl') (unit nixos-activation.service)... machine # [ 43.106530] systemd[623]: Reloading... machine # [ 43.187293] systemd[623]: Reloading finished in 79 ms. machine # [ 43.193028] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.203392] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 43.205383] systemd[623]: Reload requested from client PID 3651 ('switch-to-confi')... machine # [ 43.207104] systemd[623]: Reloading... machine # [ 43.279229] systemd[623]: Reloading finished in 70 ms. machine # [ 43.284845] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.291241] systemd[623]: Reloading migrated.service... machine # [ 43.305946] systemd[623]: Reloaded migrated.service. machine # [ 43.311405] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 43.312698] systemd[1]: Stopping Reactivate sysinit units... machine # [ 43.313763] systemd[1]: Reached target Reactivate sysinit units. machine # [ 43.316899] systemd[1]: Reached target Local File Systems. machine # [ 43.321063] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 43.324250] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 43.325103] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 43.326246] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 43.328190] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 43.329661] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.333298] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.336111] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.337426] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 43.343862] systemd[1]: Reached target Remote File Systems. machine # [ 43.468212] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 43.469913] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 43.473531] systemd[1]: Starting linger-users.service... machine # [ 43.500395] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 43.501846] systemd[1]: Finished linger-users.service. machine # [ 43.759484] nixos[3582]: finished switching to system configuration /nix/store/cwpxy45c0q1prpi6ln6czv279fsvqzpl-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 1.58 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 43.806750] sudo[3732]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 43.819782] sudo[3732]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 43.828550] sudo[3732]: 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 # [ 43.849602] sudo[3738]: 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 # [ 43.857455] sudo[3738]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 43.862767] sudo[3738]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 44.058314] nixos[3747]: switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test machine # [ 44.060431] systemd[1]: Stopped target Local File Systems. machine # [ 44.062055] systemd[1]: Stopped target Remote File Systems. machine # [ 44.267043] systemd[1]: Reload requested from client PID 3747 ('.switch-to-conf') (unit backdoor.service)... machine # [ 44.268687] systemd[1]: Reloading... machine # [ 44.497026] systemd[1]: Reloading finished in 226 ms. machine # [ 44.560207] systemd[623]: Stopped migrated.service. machine # [ 44.562079] systemd[623]: Reexecution requested from client PID 3816 ('switch-to-confi')... machine # [ 44.563306] systemd[623]: Reexecuting. machine # [ 44.583965] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 44.656362] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 44.660950] systemd[623]: Reload requested from client PID 3816 ('switch-to-confi')... machine # [ 44.662550] systemd[623]: Reloading... machine # [ 44.734167] systemd[623]: Reloading finished in 70 ms. machine # [ 44.740828] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 44.747117] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 44.748093] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 44.750577] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 44.769063] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 44.771961] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 44.773258] systemd[1]: Stopping Reactivate sysinit units... machine # [ 44.774300] systemd[1]: Reached target Reactivate sysinit units. machine # [ 44.776345] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 44.778244] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 44.780514] systemd[1]: Reached target Local File Systems. machine # [ 44.783930] systemd[1]: Reached target Remote File Systems. machine # [ 44.786459] systemd[1]: Starting linger-users.service... machine # [ 44.788773] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 44.794435] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 44.797521] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 44.798887] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 44.801437] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 44.802859] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 44.806164] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 44.844475] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 44.845969] systemd[1]: Finished linger-users.service. machine # [ 44.950334] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 44.953667] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 45.206468] nixos[3747]: finished switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.34 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 45.232364] sudo[3884]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 45.243804] sudo[3884]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 45.251887] sudo[3884]: 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.04 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 45.271761] sudo[3890]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 45.278961] sudo[3890]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 45.284438] sudo[3890]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.03 seconds) machine: must succeed: sudo -u usertest cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 45.307573] sudo[3896]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 45.315259] sudo[3896]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 45.320830] sudo[3896]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.04 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 45.340237] sudo[3902]: 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 # [ 45.347698] sudo[3902]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 45.353246] sudo[3902]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 45.372244] sudo[3908]: 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 # [ 45.379674] sudo[3908]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 45.385231] systemd[623]: Reload requested from client PID 3913 ('systemctl')... machine # [ 45.386601] systemd[623]: Reloading... machine # [ 45.461329] systemd[623]: Reloading finished in 73 ms. machine # [ 45.465837] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.471617] sudo[3908]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 45.493201] sudo[3918]: 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 # [ 45.500694] sudo[3918]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 45.509518] systemd[623]: Starting migrated.service... machine # [ 45.524901] systemd[623]: Finished migrated.service. machine # [ 45.527897] sudo[3918]: 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.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 45.547819] sudo[3926]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 45.555440] sudo[3926]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 45.561731] sudo[3926]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 45.581538] sudo[3932]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 45.589520] sudo[3932]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 45.594701] sudo[3932]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 45.617690] sudo[3938]: 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 # [ 45.627597] sudo[3938]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 45.638857] sudo[3938]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds) machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1 machine # [ 45.823552] nixos[3947]: switching to system configuration /nix/store/fvr2zrgck1z1ikj5b7nib0jch6gpwwhn-nixos-system-machine-test machine # [ 45.825856] systemd[1]: Stopped target Local File Systems. machine # [ 45.827598] systemd[1]: Stopped target Remote File Systems. machine # [ 46.046856] systemd[1]: Reload requested from client PID 3947 ('.switch-to-conf') (unit backdoor.service)... machine # [ 46.048444] systemd[1]: Reloading... machine # [ 46.275381] systemd[1]: Reloading finished in 225 ms. machine # [ 46.342268] systemd[623]: Reexecution requested from client PID 4016 ('switch-to-confi')... machine # [ 46.344323] systemd[623]: Reexecuting. machine # [ 46.365026] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.439079] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.444910] systemd[623]: Reload requested from client PID 4016 ('switch-to-confi')... machine # [ 46.446715] systemd[623]: Reloading... machine # [ 46.517323] systemd[623]: Reloading finished in 69 ms. machine # [ 46.522844] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.529944] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 46.530804] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 46.532295] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 46.555179] systemd[623]: Reload requested from client PID 4028 ('systemctl') (unit nixos-activation.service)... machine # [ 46.556862] systemd[623]: Reloading... machine # [ 46.631995] systemd[623]: Reloading finished in 73 ms. machine # [ 46.638040] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.647953] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 46.649973] systemd[623]: Reload requested from client PID 4016 ('switch-to-confi')... machine # [ 46.651791] systemd[623]: Reloading... machine # [ 46.726367] systemd[623]: Reloading finished in 73 ms. machine # [ 46.730844] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.739477] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 46.740717] systemd[1]: Stopping Reactivate sysinit units... machine # [ 46.741926] systemd[1]: Reached target Reactivate sysinit units. machine # [ 46.743169] systemd[1]: Reached target Remote File Systems. machine # [ 46.744702] systemd[1]: Reached target Local File Systems. machine # [ 46.747355] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.749102] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 46.754582] systemd[1]: Starting linger-users.service... machine # [ 46.760383] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 46.763448] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 46.764225] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 46.765351] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 46.767261] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 46.768774] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.769885] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.811730] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 46.813560] systemd[1]: Finished linger-users.service. machine # [ 46.914900] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 46.917675] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 47.172494] nixos[3947]: finished switching to system configuration /nix/store/fvr2zrgck1z1ikj5b7nib0jch6gpwwhn-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 1.54 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 47.209767] sudo[4095]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 47.223283] sudo[4095]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.230297] sudo[4095]: 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 # [ 47.249766] sudo[4101]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 47.257388] sudo[4101]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.262511] sudo[4101]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 47.441212] nixos[4110]: switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test machine # [ 47.444270] systemd[1]: Stopped target Local File Systems. machine # [ 47.445189] systemd[1]: Stopped target Remote File Systems. machine # [ 47.650737] systemd[1]: Reload requested from client PID 4110 ('.switch-to-conf') (unit backdoor.service)... machine # [ 47.652344] systemd[1]: Reloading... machine # [ 47.878441] systemd[1]: Reloading finished in 224 ms. machine # [ 47.943270] systemd[623]: Stopped migrated.service. machine # [ 47.944474] systemd[623]: Reexecution requested from client PID 4179 ('switch-to-confi')... machine # [ 47.946790] systemd[623]: Reexecuting. machine # [ 47.965982] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.040755] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.045885] systemd[623]: Reload requested from client PID 4179 ('switch-to-confi')... machine # [ 48.047330] systemd[623]: Reloading... machine # [ 48.121472] systemd[623]: Reloading finished in 72 ms. machine # [ 48.126851] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.132854] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 48.134836] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 48.136266] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 48.154888] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 48.158691] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 48.159752] systemd[1]: Stopping Reactivate sysinit units... machine # [ 48.160857] systemd[1]: Reached target Reactivate sysinit units. machine # [ 48.164347] systemd[1]: Reached target Local File Systems. machine # [ 48.166564] systemd[1]: Reached target Remote File Systems. machine # [ 48.169336] systemd[1]: Starting linger-users.service... machine # [ 48.175982] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.177297] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 48.181659] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 48.185064] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 48.185863] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 48.188253] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 48.191623] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 48.194383] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.195575] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.226871] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 48.228938] systemd[1]: Finished linger-users.service. machine # [ 48.334054] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 48.335624] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 48.592381] nixos[4110]: finished switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.33 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 48.638579] sudo[4247]: 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 # [ 48.652809] sudo[4247]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 48.663976] sudo[4247]: 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/.local/share/systemd/user machine # [ 48.686964] sudo[4253]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user machine # [ 48.694461] sudo[4253]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 48.699826] sudo[4253]: 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.04 seconds) machine: must succeed: sudo -u usertest cp /nix/store/p1fvsnwg9qs5r0w7khvj225ygkzx9zsw-migrated.service ~usertest/.local/share/systemd/user/migrated.service machine # [ 48.719363] sudo[4259]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/p1fvsnwg9qs5r0w7khvj225ygkzx9zsw-migrated.service /home/usertest/.local/share/systemd/user/migrated.service machine # [ 48.727424] sudo[4259]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 48.732862] sudo[4259]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/p1fvsnwg9qs5r0w7khvj225ygkzx9zsw-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 48.752294] sudo[4265]: 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 # [ 48.759418] sudo[4265]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 48.764912] systemd[623]: Reload requested from client PID 4270 ('systemctl')... machine # [ 48.766372] systemd[623]: Reloading... machine # [ 48.839140] systemd[623]: Reloading finished in 70 ms. machine # [ 48.842855] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.849329] sudo[4265]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 48.870663] sudo[4275]: 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 # [ 48.878571] sudo[4275]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 48.888949] systemd[623]: Starting migrated.service... machine # [ 48.903955] systemd[623]: Finished migrated.service. machine # [ 48.906462] sudo[4275]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 48.926842] sudo[4283]: 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 # [ 48.934513] sudo[4283]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 48.940962] sudo[4283]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 48.960364] sudo[4289]: 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 # [ 48.970313] sudo[4289]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 48.975873] sudo[4289]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 48.994978] sudo[4295]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 49.002467] sudo[4295]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 49.017744] sudo[4295]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds) machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 49.196602] nixos[4304]: switching to system configuration /nix/store/9j4qxxb9bs0qjns9ia9hhz886wwyrh7d-nixos-system-machine-test machine # [ 49.198969] systemd[1]: Stopped target Remote File Systems. machine # [ 49.200694] systemd[1]: Stopped target Local File Systems. machine # [ 49.419922] systemd[1]: Reload requested from client PID 4304 ('.switch-to-conf') (unit backdoor.service)... machine # [ 49.421517] systemd[1]: Reloading... machine # [ 49.646846] systemd[1]: Reloading finished in 223 ms. machine # [ 49.711270] systemd[623]: Reexecution requested from client PID 4373 ('switch-to-confi')... machine # [ 49.712478] systemd[623]: Reexecuting. machine # [ 49.732971] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.811086] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.816786] systemd[623]: Reload requested from client PID 4373 ('switch-to-confi')... machine # [ 49.818251] systemd[623]: Reloading... machine # [ 49.892388] systemd[623]: Reloading finished in 72 ms. machine # [ 49.897876] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.904750] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 49.905668] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 49.907230] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 49.926366] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 49.927886] systemd[623]: Reload requested from client PID 4373 ('switch-to-confi')... machine # [ 49.929476] systemd[623]: Reloading... machine # [ 50.001767] systemd[623]: Reloading finished in 70 ms. machine # [ 50.006840] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 50.012222] systemd[623]: Stopped migrated.service. machine # [ 50.013053] systemd[623]: Stopping migrated.service... machine # [ 50.015306] systemd[623]: Starting migrated.service... machine # [ 50.030981] systemd[623]: Finished migrated.service. machine # [ 50.036350] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 50.037627] systemd[1]: Stopping Reactivate sysinit units... machine # [ 50.038747] systemd[1]: Reached target Reactivate sysinit units. machine # [ 50.041252] systemd[1]: Reached target Local File Systems. machine # [ 50.043579] systemd[1]: Reached target Remote File Systems. machine # [ 50.048063] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 50.049247] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 50.051834] systemd[1]: Starting linger-users.service... machine # [ 50.059440] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 50.063204] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 50.065460] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 50.067440] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 50.070326] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 50.071778] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 50.072905] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 50.106507] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 50.108315] systemd[1]: Finished linger-users.service. machine # [ 50.215895] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 50.218064] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 50.474573] nixos[4304]: finished switching to system configuration /nix/store/9j4qxxb9bs0qjns9ia9hhz886wwyrh7d-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.46 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 50.521380] sudo[4447]: 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 # [ 50.535537] sudo[4447]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 50.543565] sudo[4447]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 50.563629] sudo[4453]: 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 # [ 50.571481] sudo[4453]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 50.583424] sudo[4453]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 50.603655] sudo[4459]: 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 # [ 50.612477] sudo[4459]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 50.618224] sudo[4459]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 50.800524] nixos[4468]: switching to system configuration /nix/store/9j4qxxb9bs0qjns9ia9hhz886wwyrh7d-nixos-system-machine-test machine # [ 50.803580] systemd[1]: Stopped target Local File Systems. machine # [ 50.804715] systemd[1]: Stopped target Remote File Systems. machine # [ 51.008303] systemd[1]: Reload requested from client PID 4468 ('.switch-to-conf') (unit backdoor.service)... machine # [ 51.010142] systemd[1]: Reloading... machine # [ 51.300142] systemd[1]: Reloading finished in 287 ms. machine # [ 51.365082] systemd[623]: Reexecution requested from client PID 4537 ('switch-to-confi')... machine # [ 51.366295] systemd[623]: Reexecuting. machine # [ 51.386796] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.462652] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.468364] systemd[623]: Reload requested from client PID 4537 ('switch-to-confi')... machine # [ 51.469450] systemd[623]: Reloading... machine # [ 51.540749] systemd[623]: Reloading finished in 70 ms. machine # [ 51.547066] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.553735] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 51.555435] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 51.557669] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 51.578182] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 51.581061] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 51.582542] systemd[1]: Stopping Reactivate sysinit units... machine # [ 51.583472] systemd[1]: Reached target Reactivate sysinit units. machine # [ 51.585808] systemd[1]: Reached target Remote File Systems. machine # [ 51.588116] systemd[1]: Reached target Local File Systems. machine # [ 51.594169] systemd[1]: Starting linger-users.service... machine # [ 51.597050] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.598324] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 51.603773] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 51.606439] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 51.608497] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 51.610196] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 51.612160] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 51.613675] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.616938] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.654723] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 51.656783] systemd[1]: Finished linger-users.service. machine # [ 51.756545] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 51.758167] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 52.016204] nixos[4468]: finished switching to system configuration /nix/store/9j4qxxb9bs0qjns9ia9hhz886wwyrh7d-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.40 seconds) machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd machine # [ 52.060709] sudo[4605]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd machine # [ 52.074921] sudo[4605]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.084762] sudo[4605]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 52.104649] sudo[4611]: 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.111992] sudo[4611]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.117722] systemd[623]: Reload requested from client PID 4616 ('systemctl')... machine # [ 52.118870] systemd[623]: Reloading... machine # [ 52.192376] systemd[623]: Reloading finished in 71 ms. machine # [ 52.197846] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.205408] sudo[4611]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds) machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 52.382240] nixos[4624]: switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test machine # [ 52.384538] systemd[1]: Stopped target Local File Systems. machine # [ 52.386305] systemd[1]: Stopped target Remote File Systems. machine # [ 52.593801] systemd[1]: Reload requested from client PID 4624 ('.switch-to-conf') (unit backdoor.service)... machine # [ 52.595378] systemd[1]: Reloading... machine # [ 52.832073] systemd[1]: Reloading finished in 234 ms. machine # [ 52.895282] systemd[623]: Stopped migrated.service. machine # [ 52.896574] systemd[623]: Reexecution requested from client PID 4693 ('switch-to-confi')... machine # [ 52.898938] systemd[623]: Reexecuting. machine # [ 52.920083] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.993439] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.998678] systemd[623]: Reload requested from client PID 4693 ('switch-to-confi')... machine # [ 52.999825] systemd[623]: Reloading... machine # [ 53.072148] systemd[623]: Reloading finished in 70 ms. machine # [ 53.077857] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.084250] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 53.085127] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 53.087174] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 53.105682] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 53.108392] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 53.109652] systemd[1]: Stopping Reactivate sysinit units... machine # [ 53.110741] systemd[1]: Reached target Reactivate sysinit units. machine # [ 53.114036] systemd[1]: Reached target Local File Systems. machine # [ 53.116241] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 53.118349] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 53.120115] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 53.121685] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 53.123537] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 53.124919] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.126107] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.130254] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.131476] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 53.136140] systemd[1]: Reached target Remote File Systems. machine # [ 53.258781] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 53.260914] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 53.264144] systemd[1]: Starting linger-users.service... machine # [ 53.291238] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 53.292683] systemd[1]: Finished linger-users.service. machine # [ 53.549673] nixos[4624]: finished switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.35 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 53.597570] sudo[4761]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 53.611962] sudo[4761]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 53.617330] sudo[4761]: 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/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 53.637121] sudo[4767]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 53.644590] sudo[4767]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 53.650220] sudo[4767]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 53.670127] sudo[4773]: 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 # [ 53.677853] sudo[4773]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 53.683383] sudo[4773]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 53.702453] sudo[4779]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload machine # [ 53.709531] sudo[4779]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 53.715120] systemd[623]: Reload requested from client PID 4784 ('systemctl')... machine # [ 53.716493] systemd[623]: Reloading... machine # [ 53.789969] systemd[623]: Reloading finished in 72 ms. machine # [ 53.794846] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.803061] sudo[4779]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 53.823461] sudo[4789]: 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 # [ 53.830726] sudo[4789]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 53.837968] systemd[623]: Starting migrated.service... machine # [ 53.855937] systemd[623]: Finished migrated.service. machine # [ 53.858954] sudo[4789]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 53.879444] sudo[4797]: 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.886801] sudo[4797]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 53.893740] sudo[4797]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 53.913444] sudo[4803]: 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.920757] sudo[4803]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 53.926396] sudo[4803]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 53.949792] sudo[4809]: 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.957238] sudo[4809]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 53.968521] sudo[4809]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds) machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 54.145597] nixos[4818]: switching to system configuration /nix/store/9j4qxxb9bs0qjns9ia9hhz886wwyrh7d-nixos-system-machine-test machine # [ 54.147870] systemd[1]: Stopped target Local File Systems. machine # [ 54.149704] systemd[1]: Stopped target Remote File Systems. machine # [ 54.352250] systemd[1]: Reload requested from client PID 4818 ('.switch-to-conf') (unit backdoor.service)... machine # [ 54.353707] systemd[1]: Reloading... machine # [ 54.583108] systemd[1]: Reloading finished in 227 ms. machine # [ 54.644934] systemd[623]: Reexecution requested from client PID 4887 ('switch-to-confi')... machine # [ 54.647386] systemd[623]: Reexecuting. machine # [ 54.666950] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.739269] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.744445] systemd[623]: Reload requested from client PID 4887 ('switch-to-confi')... machine # [ 54.746291] systemd[623]: Reloading... machine # [ 54.817258] systemd[623]: Reloading finished in 70 ms. machine # [ 54.822835] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.829380] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 54.830433] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 54.832762] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 54.851892] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 54.853543] systemd[623]: Reload requested from client PID 4887 ('switch-to-confi')... machine # [ 54.854980] systemd[623]: Reloading... machine # [ 54.926412] systemd[623]: Reloading finished in 70 ms. machine # [ 54.931825] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.940382] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 54.941740] systemd[1]: Stopping Reactivate sysinit units... machine # [ 54.942603] systemd[1]: Reached target Reactivate sysinit units. machine # [ 54.944491] systemd[1]: Reached target Remote File Systems. machine # [ 54.947406] systemd[1]: Starting linger-users.service... machine # [ 54.952310] systemd[1]: Reached target Local File Systems. machine # [ 54.953128] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 54.958388] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 54.959213] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 54.960362] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 54.962326] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 54.963749] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 54.964881] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 54.968862] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 54.970083] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 55.011157] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 55.012678] systemd[1]: Finished linger-users.service. machine # [ 55.115223] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 55.116905] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 55.374917] nixos[4818]: finished switching to system configuration /nix/store/9j4qxxb9bs0qjns9ia9hhz886wwyrh7d-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.41 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 55.423701] sudo[4959]: 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 # [ 55.437743] sudo[4959]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 55.452408] sudo[4959]: 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 # [ 55.472480] sudo[4965]: 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 # [ 55.479684] sudo[4965]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 55.485349] sudo[4965]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds) machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd machine # [ 55.504569] sudo[4971]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd machine # [ 55.511563] sudo[4971]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 55.517221] sudo[4971]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 55.536438] sudo[4977]: 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 # [ 55.543650] sudo[4977]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 55.549225] systemd[623]: Reload requested from client PID 4982 ('systemctl')... machine # [ 55.550573] systemd[623]: Reloading... machine # [ 55.623138] systemd[623]: Reloading finished in 71 ms. machine # [ 55.628864] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.636252] sudo[4977]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service machine # [ 55.657165] sudo[4987]: 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 # [ 55.664394] sudo[4987]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 55.672344] systemd[623]: Stopped migrated.service. machine # [ 55.674980] sudo[4987]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service, in 0.04 seconds) machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 55.856545] nixos[4996]: switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test machine # [ 55.858804] systemd[1]: Stopped target Local File Systems. machine # [ 55.860609] systemd[1]: Stopped target Remote File Systems. machine # [ 56.058748] systemd[1]: Reload requested from client PID 4996 ('.switch-to-conf') (unit backdoor.service)... machine # [ 56.060384] systemd[1]: Reloading... machine # [ 56.289957] systemd[1]: Reloading finished in 228 ms. machine # [ 56.350561] systemd[623]: Reexecution requested from client PID 5065 ('switch-to-confi')... machine # [ 56.353047] systemd[623]: Reexecuting. machine # [ 56.373057] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 56.445572] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 56.450564] systemd[623]: Reload requested from client PID 5065 ('switch-to-confi')... machine # [ 56.452385] systemd[623]: Reloading... machine # [ 56.523050] systemd[623]: Reloading finished in 69 ms. machine # [ 56.528829] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 56.534844] systemd[623]: Stopped Run user-specific NixOS activation. machine # [ 56.535868] systemd[623]: Stopping Run user-specific NixOS activation... machine # [ 56.538315] systemd[623]: Starting Run user-specific NixOS activation... machine # [ 56.556878] systemd[623]: Finished Run user-specific NixOS activation. machine # [ 56.559846] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 56.561215] systemd[1]: Stopping Reactivate sysinit units... machine # [ 56.562790] systemd[1]: Reached target Reactivate sysinit units. machine # [ 56.564979] systemd[1]: Reached target Remote File Systems. machine # [ 56.567698] systemd[1]: Starting linger-users.service... machine # [ 56.575348] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 56.576717] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 56.583307] systemd[1]: Reached target Local File Systems. machine # [ 56.585437] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 56.588384] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 56.589406] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 56.590509] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 56.592384] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 56.596151] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 56.597282] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 56.628818] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 56.631218] systemd[1]: Finished linger-users.service. machine # [ 56.738212] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 56.739818] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 56.996799] nixos[4996]: finished switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.33 seconds) (finished: subtest: user services, in 36.88 seconds) (finished: run the VM test script, in 57.90 seconds) test script finished in 57.99s cleanup kill QemuMachine (pid 45) machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14) machine # [2026-09-15T03:08:32Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-15T03:08:32Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-15T03:08:32Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.16 seconds) additionally exposed symbols: machine, other, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh