tribuchet: building on eliza Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script machine: waiting for unit multi-user.target machine: waiting for the VM to finish booting machine: starting vm machine # Disk image does not exist, creating the virtualisation disk image... machine: QEMU running (pid 45) machine # Formatting '/build/vm-state-machine/tmp.oL35NqkeAC', 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: 7b5cf406-d577-4923-86ce-8644149f7c36 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 # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] machine # [ 0.000000] Linux version 6.18.50 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 7 15:23:00 UTC 2026 machine # [ 0.000000] KASLR enabled machine # [ 0.000000] random: crng init done machine # [ 0.000000] Machine model: linux,dummy-virt machine # [ 0.000000] efi: UEFI not found. machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f] machine # [ 0.000000] Zone ranges: machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] DMA32 empty machine # [ 0.000000] Normal empty machine # [ 0.000000] Device empty machine # [ 0.000000] Movable zone start for each node machine # [ 0.000000] Early memory node ranges machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000 machine # [ 0.000000] psci: probing for conduit method from DT. machine # [ 0.000000] psci: PSCIv1.3 detected in firmware. machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs machine # [ 0.000000] psci: Trusted OS migration not required machine # [ 0.000000] psci: SMC Calling Convention v1.1 machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u311296 machine # [ 0.000000] Detected PIPT I-cache on CPU0 machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm) machine # [ 0.000000] CPU features: detected: GICv3 CPU interface machine # [ 0.000000] CPU features: detected: Spectre-v4 machine # [ 0.000000] CPU features: detected: Spectre-BHB machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38 machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23 machine # [ 0.000000] alternatives: applying boot alternatives machine # [ 0.000000] Kernel command line: console=ttyAMA0 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/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/init regInfo=/nix/store/xws75d06h0xdmz5g3fn2467sdxyj4y79-closure-info/registration console=ttyAMA0,115200n8 console=tty0 machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/xws75d06h0xdmz5g3fn2467sdxyj4y79-closure-info/registration", will be passed to user space. machine # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB machine # [ 0.000000] software IO TLB: area num 1. machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB) machine # [ 0.000000] Fallback order for Node 0: 0 machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 machine # [ 0.000000] Policy zone: DMA machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 machine # [ 0.000000] allocated 2097152 bytes of page_ext machine # [ 0.000000] ftrace: allocating 74885 entries in 294 pages machine # [ 0.000000] ftrace: allocated 294 pages with 4 groups machine # [ 0.000000] rcu: Hierarchical RCU implementation. machine # [ 0.000000] rcu: RCU event tracing is enabled. machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. machine # [ 0.000000] Trampoline variant of Tasks RCU enabled. machine # [ 0.000000] Rude variant of Tasks RCU enabled. machine # [ 0.000000] Tracing variant of Tasks RCU enabled. machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 machine # [ 0.000000] GICv3: 256 SPIs implemented machine # [ 0.000000] GICv3: 0 Extended SPIs implemented machine # [ 0.000000] Root IRQ handler: gic_handle_irq machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) machine # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt). machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns machine # [ 0.000031] arm-pv: using stolen time PV machine # [ 0.000387] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.000542] Console: colour dummy device 80x25 machine # [ 0.000550] printk: legacy console [tty0] enabled machine # [ 0.000732] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) machine # [ 0.000739] pid_max: default: 32768 minimum: 301 machine # [ 0.000809] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.000946] landlock: Up and running. machine # [ 0.000949] Yama: becoming mindful. machine # [ 0.001388] LSM support for eBPF active machine # [ 0.001515] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.001539] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.002596] cacheinfo: Unable to detect cache hierarchy for CPU 0 machine # [ 0.003322] rcu: Hierarchical SRCU implementation. machine # [ 0.003326] rcu: Max phase no-delay instances is 1000. machine # [ 0.004532] fsl-mc MSI: its@8080000 domain created machine # [ 0.004623] EFI services will not be available. machine # [ 0.004693] smp: Bringing up secondary CPUs ... machine # [ 0.004702] smp: Brought up 1 node, 1 CPU machine # [ 0.004705] SMP: Total of 1 processors activated. machine # [ 0.004708] CPU: All CPU(s) started at EL1 machine # [ 0.004721] CPU features: detected: Branch Target Identification machine # [ 0.004726] CPU features: detected: ARMv8.4 Translation Table Level machine # [ 0.004729] CPU features: detected: Instruction cache invalidation not required for I/D coherence machine # [ 0.004733] CPU features: detected: Data cache clean to the PoU not required for I/D coherence machine # [ 0.004737] CPU features: detected: Common not Private translations machine # [ 0.004740] CPU features: detected: CRC32 instructions machine # [ 0.004742] CPU features: detected: Data cache clean to Point of Deep Persistence machine # [ 0.004746] CPU features: detected: Data cache clean to Point of Persistence machine # [ 0.004749] CPU features: detected: Data independent timing control (DIT) machine # [ 0.004752] CPU features: detected: E0PD machine # [ 0.004754] CPU features: detected: Enhanced Counter Virtualization machine # [ 0.004758] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.004761] CPU features: detected: Enhanced Virtualization Traps machine # [ 0.004764] CPU features: detected: Fine Grained Traps machine # [ 0.004768] CPU features: detected: Generic authentication (architected QARMA5 algorithm) machine # [ 0.004773] CPU features: detected: RCpc load-acquire (LDAPR) machine # [ 0.004776] CPU features: detected: LSE atomic instructions machine # [ 0.004779] CPU features: detected: Privileged Access Never machine # [ 0.004781] CPU features: detected: PMUv3 machine # [ 0.004784] CPU features: detected: RAS Extension Support machine # [ 0.004787] CPU features: detected: RASv1p1 Extension Support machine # [ 0.004790] CPU features: detected: Random Number Generator machine # [ 0.004792] CPU features: detected: Speculation barrier (SB) machine # [ 0.004795] CPU features: detected: Stage-2 Force Write-Back machine # [ 0.004798] CPU features: detected: TLB range maintenance instructions machine # [ 0.004803] CPU features: detected: Speculative Store Bypassing Safe (SSBS) machine # [ 0.004841] alternatives: applying system-wide alternatives machine # [ 0.007844] CPU features: detected: BBM Level 2 without TLB conflict abort machine # [ 0.008027] Memory: 894652K/1048576K available (24448K kernel code, 7090K rwdata, 26348K rodata, 4736K init, 1107K bss, 112608K reserved, 32768K cma-reserved) machine # [ 0.008418] devtmpfs: initialized machine # [ 0.010077] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.010098] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.010282] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL machine # [ 0.010287] 0 pages in range for non-PLT usage machine # [ 0.010288] 508288 pages in range for PLT usage machine # [ 0.010400] pinctrl core: initialized pinctrl subsystem machine # [ 0.011138] DMI not present or invalid. machine # [ 0.014191] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.016437] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.016600] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.016759] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.016782] audit: initializing netlink subsys (disabled) machine # [ 0.017324] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.017325] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.017329] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.017331] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.017336] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.017358] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 machine # [ 0.017368] cpuidle: using governor ladder machine # [ 0.017373] cpuidle: using governor menu machine # [ 0.017587] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.017602] ASID allocator initialised with 65536 entries machine # [ 0.018718] Serial: AMBA PL011 UART driver machine # [ 0.023759] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 machine # [ 0.023898] printk: console [ttyAMA0] enabled machine # [ 0.147625] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.147646] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.147651] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages machine # [ 0.147655] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.147660] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.147664] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.147668] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages machine # [ 0.147672] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.155401] fbcon: Taking over console machine # [ 0.155418] ACPI: Interpreter disabled. machine # [ 0.157314] iommu: Default domain type: Translated machine # [ 0.157324] iommu: DMA domain TLB invalidation policy: strict mode machine # [ 0.158990] SCSI subsystem initialized machine # [ 0.164145] usbcore: registered new interface driver usbfs machine # [ 0.164182] usbcore: registered new interface driver hub machine # [ 0.164200] usbcore: registered new device driver usb machine # [ 0.164452] pps_core: LinuxPPS API ver. 1 registered machine # [ 0.164458] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti machine # [ 0.164467] PTP clock support registered machine # [ 0.164513] EDAC MC: Ver: 3.0.0 machine # [ 0.169179] scmi_core: SCMI protocol bus registered machine # [ 0.170161] FPGA manager framework machine # [ 0.171147] vgaarb: loaded machine # [ 0.171783] clocksource: Switched to clocksource arch_sys_counter machine # [ 0.176787] VFS: Disk quotas dquot_6.6.0 machine # [ 0.176824] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.180559] netfs: FS-Cache loaded machine # [ 0.180696] pnp: PnP ACPI: disabled machine # [ 0.184555] NET: Registered PF_INET protocol family machine # [ 0.184710] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.215068] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.215120] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.215148] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.215195] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.215271] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.215359] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.215417] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.215444] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.215523] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.215544] NET: Registered PF_XDP protocol family machine # [ 0.215566] PCI: CLS 0 bytes, default 64 machine # [ 0.215852] Trying to unpack rootfs image as initramfs... machine # [ 0.233664] kvm [1]: HYP mode not available machine # [ 0.329903] Initialise system trusted keyrings machine # [ 0.330670] workingset: timestamp_bits=42 max_order=18 bucket_order=0 machine # [ 0.331970] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.332760] 9p: Installing v9fs 9p2000 file system support machine # [ 0.356716] Key type asymmetric registered machine # [ 0.356750] Asymmetric key parser 'x509' registered machine # [ 0.356824] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.359013] io scheduler mq-deadline registered machine # [ 0.359024] io scheduler kyber registered machine # [ 0.372085] pl061_gpio 9030000.pl061: PL061 GPIO chip registered machine # [ 0.373547] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.373933] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: machine # [ 0.373951] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 machine # [ 0.373963] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 machine # [ 0.373971] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 machine # [ 0.373992] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits machine # [ 0.374015] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.374093] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.374102] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.374109] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.374114] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.374120] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] machine # [ 0.374177] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.374627] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.374816] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.374832] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.374861] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.374879] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.375333] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.375513] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.375529] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.375558] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.376053] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.376237] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.376253] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.376282] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.376732] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.376924] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.376941] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.376970] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.377423] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.377604] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.377619] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.377648] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.378108] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.378288] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] machine # [ 0.378304] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.378334] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.378776] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.378957] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.378972] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.379001] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.379017] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.379472] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.379661] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.379691] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.424258] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.424470] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.424500] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.424890] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.425071] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] machine # [ 0.425313] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.425603] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.425620] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.425649] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.426092] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.426270] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.426286] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.426314] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.426901] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.426913] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.426919] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.426967] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.427014] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned machine # [ 0.427062] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned machine # [ 0.427108] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.427154] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.427202] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.427250] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned machine # [ 0.427296] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned machine # [ 0.427341] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned machine # [ 0.427416] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.427461] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.427482] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.427503] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned machine # [ 0.427524] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned machine # [ 0.427546] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned machine # [ 0.427570] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned machine # [ 0.427592] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned machine # [ 0.427613] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned machine # [ 0.427634] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned machine # [ 0.427656] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned machine # [ 0.427678] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned machine # [ 0.427700] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned machine # [ 0.427720] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned machine # [ 0.427741] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned machine # [ 0.427762] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned machine # [ 0.427782] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned machine # [ 0.472028] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned machine # [ 0.472054] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned machine # [ 0.472077] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned machine # [ 0.472099] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned machine # [ 0.472122] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned machine # [ 0.472156] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] machine # [ 0.472166] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] machine # [ 0.472171] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] machine # [ 0.473330] pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.500340] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) machine # [ 0.502485] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) machine # [ 0.512443] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) machine # [ 0.514898] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) machine # [ 0.525605] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) machine # [ 0.528653] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) machine # [ 0.530752] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) machine # [ 0.541298] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.543676] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) machine # [ 0.553765] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) machine # [ 0.556880] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) machine # [ 0.569984] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.572533] msm_serial: driver initialized machine # [ 0.572672] SuperH (H)SCI(F) driver initialized machine # [ 0.572726] STM32 USART driver initialized machine # [ 0.606788] loop: module loaded machine # [ 0.606990] virtio_blk virtio5: 1/0/0 default/read/poll queues machine # [ 0.607771] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 0.612459] megasas: 07.734.00.00-rc1 machine # [ 0.613166] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] machine # [ 0.625020] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.625067] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.626830] Using buffer write method machine # [ 0.626933] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] machine # [ 0.640508] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.640550] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.642547] Using buffer write method machine # [ 0.642584] Concatenating MTD devices: machine # [ 0.642588] (0): "0.flash" machine # [ 0.642592] (1): "0.flash" machine # [ 0.642595] into device "0.flash" machine # [ 0.909690] Freeing initrd memory: 25748K machine # [ 0.915672] tun: Universal TUN/TAP device driver, 1.6 machine # [ 0.919637] thunder_xcv, ver 1.0 machine # [ 0.919690] thunder_bgx, ver 1.0 machine # [ 0.919710] nicpf, ver 1.0 machine # [ 0.921529] e1000: Intel(R) PRO/1000 Network Driver machine # [ 0.921543] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 0.921569] e1000e: Intel(R) PRO/1000 Network Driver machine # [ 0.921581] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. machine # [ 0.921605] igb: Intel(R) Gigabit Ethernet Network Driver machine # [ 0.921611] igb: Copyright (c) 2007-2014 Intel Corporation. machine # [ 0.921631] igbvf: Intel(R) Gigabit Virtual Function Network Driver machine # [ 0.921637] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 0.921767] sky2: driver version 1.30 machine # [ 0.923321] usbcore: registered new interface driver usb-storage machine # [ 0.923418] usbcore: registered new interface driver usbserial_generic machine # [ 0.923432] usbserial: USB Serial support registered for generic machine # [ 0.924516] ehci-pci 0000:00:0a.0: EHCI Host Controller machine # [ 0.924554] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 machine # [ 0.924770] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 machine # [ 0.935724] hv_vmbus: registering driver hyperv_keyboard machine # [ 0.935843] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 machine # [ 0.936190] hub 1-0:1.0: USB hub found machine # [ 0.936212] hub 1-0:1.0: 6 ports detected machine # [ 0.939685] rtc-pl031 9010000.pl031: registered as rtc0 machine # [ 0.939713] rtc-pl031 9010000.pl031: setting system clock to 2026-09-10T02:43:10 UTC (1789008190) machine # [ 0.941762] i2c_dev: i2c /dev entries driver machine # [ 0.944942] sdhci: Secure Digital Host Controller Interface driver machine # [ 0.944957] sdhci: Copyright(c) Pierre Ossman machine # [ 0.945218] Synopsys Designware Multimedia Card Interface Driver machine # [ 0.945576] sdhci-pltfm: SDHCI platform and OF driver helper machine # [ 0.947229] hid: raw HID events driver (C) Jiri Kosina machine # [ 0.947464] usbcore: registered new interface driver usbhid machine # [ 0.947471] usbhid: USB HID core driver machine # [ 0.953118] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available machine # [ 0.954672] drop_monitor: Initializing network drop monitor service machine # [ 0.954838] NET: Registered PF_INET6 protocol family machine # [ 0.957909] Segment Routing with IPv6 machine # [ 0.957933] In-situ OAM (IOAM) with IPv6 machine # [ 0.957962] NET: Registered PF_PACKET protocol family machine # [ 0.959599] 9pnet: Installing 9P2000 support machine # [ 0.962513] Key type dns_resolver registered machine # [ 0.968686] registered taskstats version 1 machine # [ 0.968849] Loading compiled-in X.509 certificates machine # [ 0.977416] Demotion targets for Node 0: null machine # [ 0.977528] Key type .fscrypt registered machine # [ 0.977535] Key type fscrypt-provisioning registered machine # [ 0.977639] ima: No TPM chip found, activating TPM-bypass! machine # [ 0.977660] ima: Allocated hash algorithm: sha1 machine # [ 0.977682] ima: No architecture policies found machine # [ 0.981812] input: gpio-keys as /devices/platform/gpio-keys/input/input0 machine # [ 0.999984] clk: Disabling unused clocks machine # [ 1.000017] PM: genpd: Disabling unused power domains machine # [ 1.004376] Freeing unused kernel memory: 4736K machine # [ 1.004592] Run /init as init process machine # [ 1.022470] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.183875] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 1.340491] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 machine # [ 1.357081] systemd[1]: systemd 262-2ff84cf 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.369470] systemd[1]: Detected virtualization qemu. machine # [ 1.371480] systemd[1]: Detected architecture arm64. machine # [ 1.373565] systemd[1]: Running in initrd. machine # [ 1.376451] systemd[1]: Initializing machine ID from random generator. machine # [ 1.379359] systemd[1]: Hostname set to . machine # [ 1.424139] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0 machine # [ 1.532016] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.551906] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.587874] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.597850] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.598848] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.599936] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.600768] systemd[1]: Reached target Path Units. machine # [ 1.601384] systemd[1]: Reached target Slice Units. machine # [ 1.602022] systemd[1]: Reached target Swaps. machine # [ 1.602594] systemd[1]: Reached target Timer Units. machine # [ 1.603433] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.604511] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.605394] systemd[1]: Listening on Journal Sockets. machine # [ 1.606120] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.606819] systemd[1]: Reached target Socket Units. machine # [ 1.609003] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.616234] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 1.617140] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.624113] systemd[1]: Mounting Kernel Configuration File System... machine # [ 1.648085] systemd[1]: Starting Journal Service... machine # [ 1.659393] systemd[1]: Starting Load Kernel Modules... machine # [ 1.660462] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.676230] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.692006] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.693841] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 1.701151] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 1.702506] systemd[1]: Mounted Kernel Configuration File System. machine # [ 1.706083] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 machine # [ 1.706217] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 machine # [ 1.720173] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.730185] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.734506] systemd-journald[73]: Collecting audit messages is disabled. machine # [ 1.748173] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.752966] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 machine # [ 1.753216] [drm] features: -virgl +edid -resource_blob -host_visible machine # [ 1.753226] [drm] features: -context_init machine # [ 1.753938] [drm] number of scanouts: 1 machine # [ 1.753961] [drm] number of cap sets: 0 machine # [ 1.772535] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.775143] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.788145] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic machine # [ 1.788172] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 machine # [ 1.816273] Console: switching to colour frame buffer device 160x50 machine # [ 1.836409] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.836783] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.836810] systemd[1]: Reached target Local File Systems. machine # [ 1.840879] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.852432] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device machine # [ 1.876044] systemd[1]: Finished Load Kernel Modules. machine # [ 1.880155] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.896452] systemd-modules-load[74]: Inserted module 'dm_mod' machine # [ 1.917475] systemd[1]: Started Journal Service. machine # [ 1.904394] systemd-modules-load[74]: Module 'virtio_balloon' is built in machine # [ 1.912123] systemd-modules-load[74]: Module 'virtio_console' is built in machine # [ 1.921855] systemd-modules-load[74]: Inserted module 'virtio_gpu' machine # [ 1.922953] systemd-modules-load[74]: Module 'virtio_rng' is built in machine # [ 1.928828] systemd[1]: Starting Create System Files and Directories... machine # [ 1.944212] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.946631] systemd-udevd[80]: Using default interface naming scheme 'v261'. machine # [ 1.961324] systemd[1]: Finished Create System Files and Directories. machine # [ 1.985687] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 2.069328] systemd[1]: Starting Virtual Console Setup... machine # [ 2.137858] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.145376] systemd[1]: Finished Virtual Console Setup. machine # [ 2.769651] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.770633] systemd[1]: Reached target System Initialization. machine # [ 2.771483] systemd[1]: Reached target Basic System. machine # [ 2.907473] (udev-worker)[96]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.923246] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.932339] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.991322] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 2.994904] systemd[1]: Reached target Initrd Root Device. machine # [ 2.998649] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 3.056204] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 3.063142] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 3.070222] systemd[1]: Mounting /sysroot... machine # [ 3.128174] EXT4-fs (vda): mounted filesystem 7b5cf406-d577-4923-86ce-8644149f7c36 r/w with ordered data mode. Quota mode: none. machine # [ 3.114235] systemd[1]: Mounted /sysroot. machine # [ 3.116939] systemd[1]: Reached target Initrd Root File System. machine # [ 3.122566] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 3.156395] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 3.163888] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 3.168263] systemd[1]: Reloading... machine # [ 3.368135] systemd[1]: Reloading finished in 203 ms. machine # [ 3.398207] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace... machine # [ 3.407409] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace... machine # [ 3.412407] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 3.416644] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 3.420065] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 3.422946] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 3.439921] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 3.442986] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 3.661752] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.679061] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 3.691290] systemd[1]: Mounting /sysroot/run... machine # [ 3.702402] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.746405] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.775676] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.786064] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.799732] systemd[1]: Mounted /sysroot/run. machine # [ 3.815284] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.825875] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.830945] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.858734] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.860309] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.353718] (udev-worker)[96]: mtd0ro: Failed to find and pin callout binary "/nix/store/g748j53i4yimpg27kpkbav57qnhzxzm9-systemd-262-2ff84cf/lib/udev/mtd_probe": No such file or directory machine # [ 4.358570] (udev-worker)[96]: mtd0ro: /nix/store/g748j53i4yimpg27kpkbav57qnhzxzm9-systemd-262-2ff84cf/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory machine # [ 4.384419] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.387020] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.388471] systemd[1]: Stopping Virtual Console Setup... machine # [ 4.392271] systemd[1]: Starting Virtual Console Setup... machine # [ 4.406239] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.407396] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.408719] systemd[1]: Starting Virtual Console Setup... machine # [ 4.431389] systemd-vconsole-setup[155]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 4.435182] systemd[1]: Finished Virtual Console Setup. machine # [ 4.665208] systemd[1]: Mounting /sysroot/nix/store... machine # [ 4.730956] systemd[1]: Mounted /sysroot/nix/store. machine # [ 4.736419] systemd[1]: Reached target Initrd File Systems. machine # [ 4.742339] systemd[1]: Starting Find NixOS closure... machine # [ 4.752446] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 4.796330] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 4.799737] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. machine # [ 4.817011] systemd[1]: Finished Find NixOS closure. machine # [ 4.820270] systemd[1]: Reached target Initrd Default Target. machine # [ 4.823629] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 4.857993] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 4.862897] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 4.864364] systemd[1]: Stopped target Initrd Default Target. machine # [ 4.865402] systemd[1]: Stopped target Basic System. machine # [ 4.866380] systemd[1]: Stopped target Initrd Root Device. machine # [ 4.867385] systemd[1]: Stopped target Path Units. machine # [ 4.871569] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 4.874547] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 4.880240] systemd[1]: Stopped target Slice Units. machine # [ 4.881102] systemd[1]: Stopped target Socket Units. machine # [ 4.881969] systemd[1]: Stopped target System Initialization. machine # [ 4.882861] systemd[1]: Stopped target Swaps. machine # [ 4.884125] systemd[1]: Stopped target Timer Units. machine # [ 4.888185] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 4.889158] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 4.890061] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 4.892117] systemd[1]: Stopped Find NixOS closure. machine # [ 4.894496] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 4.897323] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.899557] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 4.902411] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.903680] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 4.912531] systemd[1]: Stopped Load Kernel Modules. machine # [ 4.913353] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 4.914621] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 4.915805] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 4.928868] systemd[1]: Stopped Create System Files and Directories. machine # [ 4.932229] systemd[1]: Stopped target Local File Systems. machine # [ 4.934223] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 4.936180] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 4.939316] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 4.940954] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 4.944488] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.945624] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.947157] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 4.951046] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 4.952166] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 4.953128] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 4.954126] systemd[1]: systemd-udevd.service: Consumed 1.493s CPU time over 3.112s wall clock time, 22.3M memory peak. machine # [ 4.955547] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 4.961006] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.964189] systemd[1]: Starting Cleanup udev Database... machine # [ 4.965199] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 4.968177] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 4.969139] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 4.972228] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 4.976100] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 4.977118] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 5.004003] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 5.007482] systemd[1]: Finished Cleanup udev Database. machine # [ 5.008794] systemd[1]: Reached target Switch Root. machine # [ 5.009981] systemd[1]: Starting NixOS Activation... machine # [ 5.178758] initrd-nixos-activation-start[180]: booting system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test machine # [ 5.248451] initrd-nixos-activation-start[180]: running activation script... machine # [ 5.744623] initrd-nixos-activation-start[203]: setting up /etc... machine # [ 6.061646] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 6.064861] systemd[1]: Finished NixOS Activation. machine # [ 6.066104] systemd[1]: Starting Switch Root... machine # [ 6.090591] systemd[1]: Switching root. machine # [ 6.294977] systemd-journald[73]: Received SIGTERM from PID 1 (systemd). machine # [ 6.920528] systemd[1]: systemd 262-2ff84cf 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 # [ 6.933785] systemd[1]: Detected virtualization qemu. machine # [ 6.938098] systemd[1]: Detected architecture arm64. machine # [ 6.940467] systemd[1]: Detected first boot. machine # [ 6.948474] systemd[1]: Initializing machine ID from random generator. machine # [ 7.139764] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 7.309346] systemd[1]: Applying preset policy. machine # [ 7.813392] systemd[1]: Populated /etc with preset unit settings. machine # [ 8.346505] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 8.347991] systemd[1]: Stopped initrd-switch-root.service. machine # [ 8.351111] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 8.354783] systemd[1]: Created slice Slice /system/getty. machine # [ 8.357586] systemd[1]: Created slice User and Session Slice. machine # [ 8.359638] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 8.361450] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 8.363951] systemd[1]: Expecting device /dev/hvc0... machine # [ 8.364993] systemd[1]: Expecting device /dev/ttyAMA0... machine # [ 8.366477] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 8.368911] systemd[1]: Stopped target initrd-fs.target. machine # [ 8.369964] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 8.371495] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 8.373787] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 8.375158] systemd[1]: Reached target Path Units. machine # [ 8.376656] systemd[1]: Reached target Remote File Systems. machine # [ 8.378873] systemd[1]: Reached target Slice Units. machine # [ 8.379949] systemd[1]: Reached target Swaps. machine # [ 8.384564] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 8.389648] systemd[1]: Listening on Process Core Dump Socket. machine # [ 8.393880] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 8.398070] systemd[1]: Listening on Factory Reset Management. machine # [ 8.399286] systemd[1]: Listening on Hostname Service Socket. machine # [ 8.405498] systemd[1]: Starting Journal Log Access Socket... machine # [ 8.407733] systemd[1]: Listening on Journal Audit Socket. machine # [ 8.412997] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 8.414567] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 8.416239] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.419034] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 8.429823] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 8.430383] systemd[1]: Listening on udev Varlink Socket. machine # [ 8.435856] systemd[1]: Mounting Huge Pages File System... machine # [ 8.439976] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 8.452355] systemd[1]: Mounting Kernel Debug File System... machine # [ 8.461233] systemd[1]: Mounting Kernel Trace File System... machine # [ 8.477732] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 8.500302] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 8.502924] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 8.517664] systemd[1]: Mounting Kernel Configuration File System... machine # [ 8.524348] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 8.527435] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 8.559492] systemd[1]: Starting Load Kernel Module fuse... machine # [ 8.567891] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 8.621746] systemd[1]: Starting Journal Service... machine # [ 8.656569] systemd[1]: Starting Load Kernel Modules... machine # [ 8.677950] fuse: init (API version 7.45) machine # [ 8.690713] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 8.710854] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 8.716831] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.751093] systemd[1]: Starting Coldplug All udev Devices... machine # [ 8.781099] systemd-journald[275]: Collecting audit messages is enabled. machine # [ 8.787762] systemd[1]: Listening on Journal Log Access Socket. machine # [ 8.796140] systemd[1]: Mounted Huge Pages File System. machine # [ 8.800740] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 8.787557] systemd[1]: Queued start job for default target Multi-User System. machine # [ 8.806615] systemd[1]: Mounted Kernel Debug File System. machine # [ 8.811184] systemd[1]: Started Journal Service. machine # [ 8.793204] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 8.794643] systemd-modules-load[276]: Module 'atkbd' is built in machine # [ 8.795646] systemd-modules-load[276]: Module 'loop' is built in machine # [ 8.807923] systemd[1]: Mounted Kernel Trace File System. machine # [ 8.830675] EXT4-fs (vda): re-mounted 7b5cf406-d577-4923-86ce-8644149f7c36. machine # [ 8.818358] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 8.823511] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 8.830885] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 8.834364] systemd[1]: Mounted Kernel Configuration File System. machine # [ 8.841779] systemd[1]: modprobe@fuse.service: Deactivated successfully. machine # [ 8.842959] systemd[1]: Finished Load Kernel Module fuse. machine # [ 8.843902] systemd[1]: Finished Load Kernel Modules. machine # [ 8.856404] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 8.863299] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 8.886373] systemd[1]: Mounting FUSE Control File System... machine # [ 8.889711] systemd[1]: Starting Firewall... machine # [ 8.929142] systemd-oomd[277]: No swap; memory pressure usage will be degraded machine # [ 8.934522] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 8.939113] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 8.953990] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 8.978458] systemd[1]: Starting Apply Kernel Variables... machine # [ 9.029773] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 9.033603] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 9.040590] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 9.127008] systemd-journald[275]: Received client request to flush runtime journal. machine # [ 9.205295] systemd[1]: Mounted FUSE Control File System. machine # [ 9.216607] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 9.217455] systemd[1]: Reached target First Boot Complete. machine # [ 9.229552] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 9.231373] systemd[1]: Finished Apply Kernel Variables. machine # [ 9.272002] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 9.280481] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 9.393937] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 9.397747] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 9.407519] systemd[1]: Mounting /run/wrappers... machine # [ 9.416557] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 9.501400] systemd[1]: Mounted /run/wrappers. machine # [ 9.502612] systemd[1]: Reached target Local File Systems. machine # [ 9.512342] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 9.516855] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 9.525204] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 9.530865] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 9.561953] systemd[1]: Starting Create System Files and Directories... machine # [ 9.624871] systemd-udevd[324]: Using default interface naming scheme 'v261'. machine # [ 9.655828] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 9.669545] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 9.737522] systemd[1]: Finished Create System Files and Directories. machine # [ 9.748602] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 9.766334] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 9.898399] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 9.938245] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 9.951348] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 9.975335] systemd[1]: Starting Update is Completed... machine # [ 10.066204] systemd[1]: Finished Update is Completed. machine # [ 10.420537] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 10.425639] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 10.912175] systemd[1]: Finished Coldplug All udev Devices. machine # [ 10.913190] systemd[1]: Reached target System Initialization. machine # [ 10.914047] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 10.915016] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 10.915954] systemd[1]: Reached target Timer Units. machine # [ 10.924860] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 10.926139] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 10.933588] systemd[1]: Reached target Socket Units. machine # [ 10.942371] systemd[1]: Reached target Basic System. machine # [ 10.943142] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 10.947824] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 10.958096] systemd[1]: Starting Post-Boot Actions... machine # [ 11.006728] systemd[1]: Started Reset console on configuration changes. machine # [ 11.031904] systemd[1]: Starting resolvconf update... machine # [ 11.126189] systemd[1]: Finished Post-Boot Actions. machine # [ 11.156797] nsncd[461]: Sep 10 02:43:20.735 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 11.170225] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 11.177152] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 11.183119] systemd[1]: Reached target User and Group Name Lookups. machine # [ 11.200943] systemd[1]: Starting D-Bus System Message Bus... machine # [ 11.251477] systemd[1]: Starting User Login Management... machine # [ 11.253674] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 11.303149] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 11.348671] systemd[1]: Finished Firewall. machine # [ 11.481991] dbus-broker-launch[489]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 11.490153] dbus-broker-launch[489]: NSS returned no entry for 'systemd-timesync' machine # [ 11.498115] dbus-broker-launch[489]: Invalid user-name in /nix/store/xnm1mc93sr4ddyc8s8wj9j9mpjkna1hq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 11.507961] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 11.514303] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 11.519990] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 11.525616] systemd[1]: Stopping User and Group Name Lookups... machine # [ 11.530693] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 11.542021] systemd-logind[493]: New seat seat0. machine # [ 11.544343] systemd[1]: Started User Login Management. machine # [ 11.549854] systemd[1]: nscd.service: Deactivated successfully. machine # [ 11.553122] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 11.558472] systemd[1]: Started D-Bus System Message Bus. machine # [ 11.561349] systemd[1]: Starting linger-users.service... machine # [ 11.567287] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 11.610611] dbus-broker-launch[489]: Ready machine # [ 11.682783] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 11.688576] nsncd[550]: Sep 10 02:43:21.260 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 11.698507] systemd[1]: Finished resolvconf update. machine # [ 11.700392] systemd[1]: Reached target Preparation for Network. machine # [ 11.708203] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 11.711414] systemd[1]: Reached target User and Group Name Lookups. machine # [ 11.715554] systemd[1]: Starting DHCP Client... machine # [ 11.720953] systemd[1]: Starting Extra networking commands.... machine # [ 11.812903] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 11.820364] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 11.825090] systemd[1]: Finished linger-users.service. machine # [ 11.856386] systemd[1]: Created slice Slice /user/1001. machine # [ 11.878939] systemd[1]: Starting User Runtime Directory /run/user/1001... machine # [ 11.922525] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. machine # [ 11.950662] systemd[1]: Started backdoor.service. machine # [ 12.052963] systemd[1]: Finished User Runtime Directory /run/user/1001. machine # [ 12.076636] systemd[1]: Starting User Manager for UID 1001... machine # [ 12.216751] (udev-worker)[370]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 12.262163] (udev-worker)[370]: Network interface NamePolicy= disabled on kernel command line. machine # [ 12.287503] (systemd)[584]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 12.333304] (udev-worker)[383]: Network interface NamePolicy= disabled on kernel command line. machine # [ 12.375503] systemd-logind[493]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'. machine # [ 12.424600] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # connecting to host... machine # [ 12.625129] mousedev: PS/2 mouse device common for all mice machine # [ 12.655046] systemd-logind[493]: Watching system buttons on /dev/input/event0 (gpio-keys) machine # [ 12.668429] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 12.669733] systemd[1]: Starting Address configuration of eth1... machine # [ 12.670590] systemd[584]: Queued start job for default target Main User Target. machine: Guest shell says: b'Spawning backdoor root shell...\n' machine: connected to guest root shell machine: (connecting took 13.00 seconds) machine: (finished: waiting for the VM to finish booting, in 13.73 seconds) machine # [ 12.707505] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 12.724368] systemd[584]: Created slice User Application Slice. machine # [ 12.725334] systemd[584]: Started Daily Cleanup of User's Temporary Directories. machine # [ 12.726367] systemd[584]: Reached target Paths. machine # [ 12.727032] systemd[584]: Reached target Timers. machine # [ 12.727680] systemd[584]: Starting D-Bus User Message Bus Socket... machine # [ 12.777216] systemd[584]: Starting Create User Files and Directories... machine # [ 12.796954] systemd[584]: Finished Create User Files and Directories. machine # [ 12.850132] systemd[584]: Listening on D-Bus User Message Bus Socket. machine # [ 12.868670] systemd[584]: Reached target Sockets. machine # [ 12.869456] systemd[584]: Reached target Basic System. machine # [ 12.870174] systemd[1]: Started User Manager for UID 1001. machine # [ 12.870942] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 12.898343] systemd-logind[493]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 12.935594] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 12.939725] systemd[584]: Reached target Main User Target. machine # [ 12.948323] systemd[584]: Startup finished in 619ms. machine # [ 12.975987] dhcpcd[592]: dhcpcd-10.3.2 starting machine # [ 12.996545] dhcpcd[659]: dev: loaded udev machine # [ 13.010487] network-addresses-eth1-start[612]: adding address 192.168.1.1/24... done machine # [ 13.027232] network-addresses-eth1-start[612]: adding address 2001:db8:1::1/64... done machine # [ 13.064859] 8021q: 802.1Q VLAN Support v1.8 machine # [ 13.065252] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 13.090744] systemd[1]: Finished Extra networking commands.. machine # [ 13.100548] systemd[1]: Finished Address configuration of eth1. machine # [ 13.105650] systemd[1]: Reached target Network. machine # [ 13.108512] systemd[1]: Starting Permit User Sessions... machine # [ 13.157802] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 13.194021] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 13.194594] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 13.200109] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 13.200466] cfg80211: failed to load regulatory.db machine # [ 13.215329] systemd[1]: Finished Permit User Sessions. machine # [ 13.221815] systemd[1]: Started Getty on tty1. machine # [ 13.225222] systemd[1]: Reached target Login Prompts. machine # [ 13.520376] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 13.512988] dhcpcd[659]: eth0: waiting for carrier machine # [ 13.522007] dhcpcd[659]: eth0: carrier acquired machine # [ 13.599263] dhcpcd[659]: DUID 00:01:00:01:32:34:d5:cb:52:54:00:12:34:56 machine # [ 13.614167] dhcpcd[659]: eth0: IAID 00:12:34:56 machine # [ 13.614959] dhcpcd[659]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 14.021069] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3 machine # [ 14.375572] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 14.409509] systemd[1]: Starting Virtual Console Setup... machine # [ 14.457473] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 14.459324] systemd[1]: Stopped Virtual Console Setup. machine # [ 14.465151] systemd-logind[493]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 14.466363] systemd[1]: Starting Virtual Console Setup... machine # [ 14.949009] systemd-vconsole-setup[713]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 14.955645] systemd[1]: Finished Virtual Console Setup. machine # [ 14.966424] dhcpcd[659]: eth0: soliciting a DHCP lease machine # [ 14.972890] dhcpcd[659]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 14.980496] dhcpcd[659]: eth0: probing address 10.0.2.15/24 machine # [ 16.059360] dhcpcd[659]: eth0: soliciting an IPv6 router machine # [ 16.063187] dhcpcd[659]: eth0: Router Advertisement from fe80::2 machine # [ 16.063383] dhcpcd[659]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 16.063519] dhcpcd[659]: eth0: adding route to fec0::/64 machine # [ 16.063655] dhcpcd[659]: eth0: adding default route via fe80::2 machine # [ 19.539938] dhcpcd[659]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 19.544141] dhcpcd[659]: eth0: adding route to 10.0.2.0/24 machine # [ 19.547145] dhcpcd[659]: eth0: adding default route via 10.0.2.2 machine # [ 19.746373] systemd[1]: Started DHCP Client. machine # [ 19.749021] systemd[1]: Reached target Multi-User System. machine # [ 19.750410] systemd[1]: Startup finished in 989ms (kernel) + 5.416s (initrd) + 13.342s (userspace) = 19.748s. machine: (finished: waiting for unit multi-user.target, in 21.17 seconds) machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test machine # Checking switch inhibitors... done machine # [ 20.756533] nixos[826]: switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test machine # [ 20.759268] systemd[1]: Stopped target Local File Systems. machine # activating the configuration... machine # [ 20.761688] systemd[1]: Stopped target Remote File Systems. machine # setting up /etc... machine # [ 21.227189] systemd[1]: Reload requested from client PID 826 ('.switch-to-conf') (unit backdoor.service)... machine # [ 21.229224] systemd[1]: Reloading... machine # [ 21.817811] systemd[1]: Reloading finished in 585 ms. machine # reloading user units for usertest... machine # [ 21.866073] systemd[584]: Created slice User Core Session Slice. machine # [ 21.869758] systemd[584]: Starting D-Bus User Message Bus... machine # [ 21.932653] dbus-broker-launch[896]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/xnm1mc93sr4ddyc8s8wj9j9mpjkna1hq-system-path/share/dbus-1/services/org.freedesktop.import1.service' machine # [ 21.936459] dbus-broker-launch[896]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/xnm1mc93sr4ddyc8s8wj9j9mpjkna1hq-system-path/share/dbus-1/services/org.freedesktop.machine1.service' machine # [ 21.941896] dbus-broker-launch[896]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/xnm1mc93sr4ddyc8s8wj9j9mpjkna1hq-system-path/share/dbus-1/services/org.freedesktop.portable1.service' machine # [ 21.945050] dbus-broker-launch[896]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/xnm1mc93sr4ddyc8s8wj9j9mpjkna1hq-system-path/share/dbus-1/services/org.freedesktop.systemd1.service' machine # [ 21.948733] dbus-broker-launch[896]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored machine # [ 21.951763] dbus-broker-launch[896]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored machine # [ 21.954210] systemd[584]: Started D-Bus User Message Bus. machine # [ 21.962023] dbus-broker-launch[896]: Ready machine # [ 22.072824] systemd[584]: Reexecution requested from client PID 894 ('switch-to-confi')... machine # [ 22.076852] systemd[584]: Reexecuting. machine # [ 22.126542] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 22.322325] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 22.329154] systemd[584]: Reload requested from client PID 894 ('switch-to-confi')... machine # [ 22.331493] systemd[584]: Reloading... machine # [ 22.523893] systemd[584]: Reloading finished in 191 ms. machine # [ 22.536156] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 22.549186] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 22.550154] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 22.552923] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 22.599716] systemd[584]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 22.617012] systemd[1]: Starting Re-setup tmpfiles on a system that is already running.... machine # [ 22.722825] systemd[1]: Finished Re-setup tmpfiles on a system that is already running.. machine # [ 22.725207] systemd[1]: Reached target Reactivate sysinit units. machine # [ 22.726869] systemd[1]: Reached target Local File Systems. machine # [ 22.734513] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.737042] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 22.739534] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 22.747157] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 22.748446] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 22.751978] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 22.754661] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 22.756325] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.757906] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.763478] systemd[1]: Reached target Remote File Systems. machine # [ 23.097984] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 23.102632] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 23.105483] systemd[1]: Starting linger-users.service... machine # [ 23.166016] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 23.168659] systemd[1]: Finished linger-users.service. machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service machine # [ 23.432774] nixos[826]: finished switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test, in 3.30 seconds) machine: must succeed: rm /etc/NIXOS machine: (finished: must succeed: rm /etc/NIXOS, in 0.05 seconds) machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test/bin/switch-to-configuration test machine # this should succeed (config: /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test, action: test) machine # Checking switch inhibitors... done machine # [ 24.121919] nixos[974]: switching to system configuration /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test machine # [ 24.124825] systemd[1]: Stopped target Local File Systems. machine # activating the configuration... machine # [ 24.127513] systemd[1]: Stopped target Remote File Systems. machine # removing user ‘usertest’ machine # setting up /etc... machine # [ 24.630861] systemd[1]: Reload requested from client PID 974 ('.switch-to-conf') (unit backdoor.service)... machine # [ 24.633074] systemd[1]: Reloading... machine # [ 25.309853] systemd[1]: Reloading finished in 674 ms. machine # reloading user units for usertest... machine # [ 25.484306] systemd[584]: Reexecution requested from client PID 1045 ('switch-to-confi')... machine # [ 25.485784] systemd[584]: Reexecuting. machine # [ 25.550382] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 25.759738] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 25.766574] systemd[584]: Reload requested from client PID 1045 ('switch-to-confi')... machine # [ 25.769249] systemd[584]: Reloading... machine # [ 25.970623] systemd[584]: Reloading finished in 200 ms. machine # [ 25.984200] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 25.995888] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 25.998709] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 26.002004] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 26.054730] systemd[584]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 26.059611] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 26.061282] systemd[1]: Stopping Reactivate sysinit units... machine # [ 26.062135] systemd[1]: Reached target Reactivate sysinit units. machine # reloading the following units: dbus-broker.service machine # [ 26.065558] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 26.066786] dbus-broker-launch[489]: Caught SIGHUP, trigger reload. machine # [ 26.091849] dbus-broker-launch[489]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 26.094344] dbus-broker-launch[489]: NSS returned no entry for 'systemd-timesync' machine # [ 26.095676] dbus-broker-launch[489]: Invalid user-name in /nix/store/p8lzwq9djsvv4pad8njyi3hknk69n4y5-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 26.125488] systemd[1]: Reloaded D-Bus System Message Bus. machine # restarting the following units: network-addresses-eth1.service machine # [ 26.133737] systemd[1]: Stopping Address configuration of eth1... machine # [ 26.187589] network-addresses-eth1-pre-stop[1057]: deleting address 192.168.1.1/24... done machine # [ 26.199992] network-addresses-eth1-pre-stop[1057]: deleting address 2001:db8:1::1/64... done machine # [ 26.210676] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 26.215138] systemd[1]: Stopped Address configuration of eth1. machine # [ 26.218141] systemd[1]: Starting Address configuration of eth1... machine # [ 26.292437] network-addresses-eth1-start[1062]: adding address 192.168.1.2/24... done machine # [ 26.305243] network-addresses-eth1-start[1062]: adding address 2001:db8:1::2/64... done machine # [ 26.321897] systemd[1]: Finished Address configuration of eth1. machine # [ 26.325865] systemd[1]: Reached target Local File Systems. machine # [ 26.329105] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 26.334415] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 26.335334] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 26.346131] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 26.358813] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 26.364448] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.365847] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.367272] systemd[1]: Reached target Remote File Systems. machine # [ 26.368246] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.369784] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 26.798575] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 26.804404] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 26.807401] systemd[1]: Starting linger-users.service... machine # [ 26.860343] linger-users-start[1135]: id: ‘usertest’: no such user machine # [ 26.863213] linger-users-start[1133]: Removing linger for missing user usertest machine # [ 26.874257] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 26.877491] systemd[1]: Finished linger-users.service. machine # [ 27.135750] nixos[974]: finished switching to system configuration /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test/bin/switch-to-configuration test, in 3.65 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 27.723638] nixos[1141]: switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test machine # [ 27.727918] systemd[1]: Stopped target Remote File Systems. machine # [ 27.729083] systemd[1]: Stopped target Local File Systems. machine # [ 28.180513] systemd[1]: Reload requested from client PID 1141 ('.switch-to-conf') (unit backdoor.service)... machine # [ 28.182510] systemd[1]: Reloading... machine # [ 28.768714] systemd[1]: Reloading finished in 583 ms. machine # [ 28.918193] systemd[584]: Reexecution requested from client PID 1210 ('switch-to-confi')... machine # [ 28.921774] systemd[584]: Reexecuting. machine # [ 28.983805] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.186301] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.193009] systemd[584]: Reload requested from client PID 1210 ('switch-to-confi')... machine # [ 29.195588] systemd[584]: Reloading... machine # [ 29.399330] systemd[584]: Reloading finished in 202 ms. machine # [ 29.416231] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.427455] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 29.430135] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 29.433474] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 29.485211] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 29.489763] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 29.490822] systemd[1]: Stopping Reactivate sysinit units... machine # [ 29.494792] systemd[1]: Reached target Reactivate sysinit units. machine # [ 29.495814] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 29.497679] dbus-broker-launch[489]: Caught SIGHUP, trigger reload. machine # [ 29.512411] dbus-broker-launch[489]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 29.514809] dbus-broker-launch[489]: NSS returned no entry for 'systemd-timesync' machine # [ 29.516190] dbus-broker-launch[489]: Invalid user-name in /nix/store/xnm1mc93sr4ddyc8s8wj9j9mpjkna1hq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 29.542386] systemd[1]: Reloaded D-Bus System Message Bus. machine # [ 29.549605] systemd[1]: Stopping Address configuration of eth1... machine # [ 29.603275] network-addresses-eth1-pre-stop[1222]: deleting address 192.168.1.2/24... done machine # [ 29.616197] network-addresses-eth1-pre-stop[1222]: deleting address 2001:db8:1::2/64... done machine # [ 29.626682] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 29.629053] systemd[1]: Stopped Address configuration of eth1. machine # [ 29.634241] systemd[1]: Starting Address configuration of eth1... machine # [ 29.729023] network-addresses-eth1-start[1227]: adding address 192.168.1.1/24... done machine # [ 29.743933] network-addresses-eth1-start[1227]: adding address 2001:db8:1::1/64... done machine # [ 29.764560] systemd[1]: Finished Address configuration of eth1. machine # [ 29.769098] systemd[1]: Reached target Remote File Systems. machine # [ 29.773130] systemd[1]: Reached target Local File Systems. machine # [ 29.777522] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 29.781530] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 29.782414] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 29.783659] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 29.803516] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 29.810908] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 29.813545] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 29.815829] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 29.817533] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 30.150361] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 30.154140] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 30.158885] systemd[1]: Starting linger-users.service... machine # [ 30.209307] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 30.212340] systemd[1]: Finished linger-users.service. machine # [ 30.471177] nixos[1141]: finished switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.33 seconds) subtest: user services machine: waiting for unit user@1001.service machine: (finished: waiting for unit user@1001.service, in 0.21 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 31.255967] nixos[1301]: switching to system configuration /nix/store/pshfghz8n8vb36ryvh6ydxvz2gkqfzj0-nixos-system-machine-test machine # [ 31.258741] systemd[1]: Stopped target Local File Systems. machine # [ 31.261316] systemd[1]: Stopped target Remote File Systems. machine # [ 31.788745] systemd[1]: Reload requested from client PID 1301 ('.switch-to-conf') (unit backdoor.service)... machine # [ 31.790801] systemd[1]: Reloading... machine # [ 32.357920] systemd[1]: Reloading finished in 564 ms. machine # [ 32.530205] systemd[584]: Reexecution requested from client PID 1370 ('switch-to-confi')... machine # [ 32.531601] systemd[584]: Reexecuting. machine # [ 32.594171] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.812973] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.819951] systemd[584]: Reload requested from client PID 1370 ('switch-to-confi')... machine # [ 32.822426] systemd[584]: Reloading... machine # [ 33.033541] systemd[584]: Reloading finished in 209 ms. machine # [ 33.057964] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.068324] systemd[584]: Starting usertest.service... machine # [ 33.115593] systemd[584]: Finished usertest.service. machine # [ 33.118019] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 33.120263] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 33.123509] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 33.169674] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 33.173923] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 33.176834] systemd[1]: Stopping Reactivate sysinit units... machine # [ 33.177695] systemd[1]: Reached target Reactivate sysinit units. machine # [ 33.178509] systemd[1]: Reached target Remote File Systems. machine # [ 33.179985] systemd[1]: Reached target Local File Systems. machine # [ 33.188721] systemd[1]: Starting linger-users.service... machine # [ 33.193358] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 33.202320] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 33.203412] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 33.206335] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 33.217109] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 33.218757] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.224505] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.226440] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.227971] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 33.287476] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 33.290262] systemd[1]: Finished linger-users.service. machine # [ 33.571268] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 33.574889] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 33.831646] nixos[1301]: finished switching to system configuration /nix/store/pshfghz8n8vb36ryvh6ydxvz2gkqfzj0-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 3.16 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 33.945790] sudo[1441]: 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.968643] sudo[1441]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 33.986891] sudo[1441]: 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.15 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 34.541019] nixos[1450]: switching to system configuration /nix/store/pshfghz8n8vb36ryvh6ydxvz2gkqfzj0-nixos-system-machine-test machine # [ 34.543454] systemd[1]: Stopped target Local File Systems. machine # [ 34.545734] systemd[1]: Stopped target Remote File Systems. machine # [ 34.998758] systemd[1]: Reload requested from client PID 1450 ('.switch-to-conf') (unit backdoor.service)... machine # [ 35.000876] systemd[1]: Reloading... machine # [ 35.613253] systemd[1]: Reloading finished in 610 ms. machine # [ 35.774560] systemd[584]: Reexecution requested from client PID 1519 ('switch-to-confi')... machine # [ 35.779076] systemd[584]: Reexecuting. machine # [ 35.826917] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.033368] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.040063] systemd[584]: Reload requested from client PID 1519 ('switch-to-confi')... machine # [ 36.042445] systemd[584]: Reloading... machine # [ 36.247496] systemd[584]: Reloading finished in 203 ms. machine # [ 36.260167] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.269971] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 36.272806] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 36.276978] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 36.332543] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 36.340156] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 36.341160] systemd[1]: Stopping Reactivate sysinit units... machine # [ 36.341966] systemd[1]: Reached target Reactivate sysinit units. machine # [ 36.349148] systemd[1]: Reached target Remote File Systems. machine # [ 36.354358] systemd[1]: Starting linger-users.service... machine # [ 36.358224] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.361886] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 36.369267] systemd[1]: Reached target Local File Systems. machine # [ 36.374568] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 36.380359] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 36.392154] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 36.393660] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 36.399873] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 36.403175] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.406600] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 36.489269] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 36.492893] systemd[1]: Finished linger-users.service. machine # [ 36.784727] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 36.787613] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 37.046943] nixos[1450]: finished switching to system configuration /nix/store/pshfghz8n8vb36ryvh6ydxvz2gkqfzj0-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 3.07 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1 machine # [ 37.661147] nixos[1591]: switching to system configuration /nix/store/c42prpkib9w329y9261yvpwpsfdlmkr9-nixos-system-machine-test machine # [ 37.663923] systemd[1]: Stopped target Local File Systems. machine # [ 37.666398] systemd[1]: Stopped target Remote File Systems. machine # [ 38.185444] systemd[1]: Reload requested from client PID 1591 ('.switch-to-conf') (unit backdoor.service)... machine # [ 38.187381] systemd[1]: Reloading... machine # [ 38.786084] systemd[1]: Reloading finished in 595 ms. machine # [ 38.965239] systemd[584]: Stopped usertest.service. machine # [ 38.968857] systemd[584]: Reexecution requested from client PID 1660 ('switch-to-confi')... machine # [ 38.971510] systemd[584]: Reexecuting. machine # [ 39.035074] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.249406] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.257282] systemd[584]: Reload requested from client PID 1660 ('switch-to-confi')... machine # [ 39.260106] systemd[584]: Reloading... machine # [ 39.462809] systemd[584]: Reloading finished in 203 ms. machine # [ 39.476191] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.488407] systemd[584]: Starting usertest.service... machine # [ 39.528951] systemd[584]: Finished usertest.service. machine # [ 39.532103] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 39.533083] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 39.538223] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 39.588542] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 39.593064] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 39.595909] systemd[1]: Stopping Reactivate sysinit units... machine # [ 39.597791] systemd[1]: Reached target Reactivate sysinit units. machine # [ 39.598765] systemd[1]: Reached target Local File Systems. machine # [ 39.602650] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 39.608515] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 39.610982] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 39.616603] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 39.618840] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 39.627421] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.630961] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.635106] systemd[1]: Reached target Remote File Systems. machine # [ 39.636187] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.638320] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 40.033293] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 40.034532] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 40.040790] systemd[1]: Starting linger-users.service... machine # [ 40.111327] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 40.114350] systemd[1]: Finished linger-users.service. machine # [ 40.372908] nixos[1591]: finished switching to system configuration /nix/store/c42prpkib9w329y9261yvpwpsfdlmkr9-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 3.32 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 40.462621] sudo[1731]: 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 # [ 40.481631] sudo[1731]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 40.496684] sudo[1731]: 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.11 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 41.032409] nixos[1740]: switching to system configuration /nix/store/b5xsfk33ln102fr8jl2wvr3p0xxww71d-nixos-system-machine-test machine # [ 41.036719] systemd[1]: Stopped target Local File Systems. machine # [ 41.038056] systemd[1]: Stopped target Remote File Systems. machine # [ 41.557577] systemd[1]: Reload requested from client PID 1740 ('.switch-to-conf') (unit backdoor.service)... machine # [ 41.559616] systemd[1]: Reloading... machine # [ 42.153241] systemd[1]: Reloading finished in 589 ms. machine # [ 42.330622] systemd[584]: Reexecution requested from client PID 1809 ('switch-to-confi')... machine # [ 42.333011] systemd[584]: Reexecuting. machine # [ 42.395609] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.611301] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.619414] systemd[584]: Reload requested from client PID 1809 ('switch-to-confi')... machine # [ 42.622910] systemd[584]: Reloading... machine # [ 42.820223] systemd[584]: Reloading finished in 198 ms. machine # [ 42.836204] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.842626] systemd[584]: Stopped usertest.service. machine # [ 42.844852] systemd[584]: Stopping usertest.service... machine # [ 42.847588] systemd[584]: Starting usertest.service... machine # [ 42.884238] systemd[584]: Finished usertest.service. machine # [ 42.889777] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 42.892399] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 42.895569] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 42.943271] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 42.947761] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 42.949600] systemd[1]: Stopping Reactivate sysinit units... machine # [ 42.950441] systemd[1]: Reached target Reactivate sysinit units. machine # [ 42.953479] systemd[1]: Reached target Remote File Systems. machine # [ 42.959366] systemd[1]: Starting linger-users.service... machine # [ 42.970876] systemd[1]: Reached target Local File Systems. machine # [ 42.973207] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 42.983673] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 42.989529] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 42.990825] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 43.003873] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 43.008216] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.009902] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.015182] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.017989] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 43.104150] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 43.109214] systemd[1]: Finished linger-users.service. machine # [ 43.394939] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 43.398614] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 43.655249] nixos[1740]: finished switching to system configuration /nix/store/b5xsfk33ln102fr8jl2wvr3p0xxww71d-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1, in 3.17 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 43.740269] sudo[1880]: 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 # [ 43.757812] sudo[1880]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 43.772643] sudo[1880]: 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.11 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1 machine # [ 44.327463] nixos[1889]: switching to system configuration /nix/store/b4dc468blcb1jxanc87pr5fwwy1ff403-nixos-system-machine-test machine # [ 44.330722] systemd[1]: Stopped target Remote File Systems. machine # [ 44.333251] systemd[1]: Stopped target Local File Systems. machine # [ 44.877788] systemd[1]: Reload requested from client PID 1889 ('.switch-to-conf') (unit backdoor.service)... machine # [ 44.879943] systemd[1]: Reloading... machine # [ 45.516943] systemd[1]: Reloading finished in 634 ms. machine # [ 45.710161] systemd[584]: Reexecution requested from client PID 1958 ('switch-to-confi')... machine # [ 45.711685] systemd[584]: Reexecuting. machine # [ 45.774755] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.988718] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.996419] systemd[584]: Reload requested from client PID 1958 ('switch-to-confi')... machine # [ 45.998868] systemd[584]: Reloading... machine # [ 46.209661] systemd[584]: Reloading finished in 209 ms. machine # [ 46.224205] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.235445] systemd[584]: Reloading usertest.service... machine # [ 46.277039] systemd[584]: Reloaded usertest.service. machine # [ 46.282855] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 46.285540] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 46.288724] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 46.353895] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 46.360937] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 46.361965] systemd[1]: Stopping Reactivate sysinit units... machine # [ 46.362758] systemd[1]: Reached target Reactivate sysinit units. machine # [ 46.382202] systemd[1]: Reached target Remote File Systems. machine # [ 46.386152] systemd[1]: Starting linger-users.service... machine # [ 46.401729] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.403186] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 46.410643] systemd[1]: Reached target Local File Systems. machine # [ 46.411657] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 46.421008] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 46.421995] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 46.423229] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 46.427885] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 46.441404] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.442772] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.522288] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 46.524338] systemd[1]: Finished linger-users.service. machine # [ 46.823690] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 46.826735] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 47.087894] nixos[1889]: finished switching to system configuration /nix/store/b4dc468blcb1jxanc87pr5fwwy1ff403-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1, in 3.32 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 47.183125] sudo[2029]: 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 # [ 47.206831] sudo[2029]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.224317] sudo[2029]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.13 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 47.816647] nixos[2038]: switching to system configuration /nix/store/pshfghz8n8vb36ryvh6ydxvz2gkqfzj0-nixos-system-machine-test machine # [ 47.819838] systemd[1]: Stopped target Local File Systems. machine # [ 47.822884] systemd[1]: Stopped target Remote File Systems. machine # [ 48.324760] systemd[1]: Reload requested from client PID 2038 ('.switch-to-conf') (unit backdoor.service)... machine # [ 48.326918] systemd[1]: Reloading... machine # [ 48.980402] systemd[1]: Reloading finished in 650 ms. machine # [ 49.146271] systemd[584]: Stopped usertest.service. machine # [ 49.150179] systemd[584]: Reexecution requested from client PID 2107 ('switch-to-confi')... machine # [ 49.151817] systemd[584]: Reexecuting. machine # [ 49.214781] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.422959] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.430606] systemd[584]: Reload requested from client PID 2107 ('switch-to-confi')... machine # [ 49.433377] systemd[584]: Reloading... machine # [ 49.639390] systemd[584]: Reloading finished in 204 ms. machine # [ 49.652198] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.663922] systemd[584]: Starting usertest.service... machine # [ 49.705953] systemd[584]: Finished usertest.service. machine # [ 49.709172] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 49.711769] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 49.714863] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 49.768250] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 49.773244] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 49.774268] systemd[1]: Stopping Reactivate sysinit units... machine # [ 49.777092] systemd[1]: Reached target Reactivate sysinit units. machine # [ 49.780885] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 49.782275] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 49.785966] systemd[1]: Reached target Remote File Systems. machine # [ 49.792701] systemd[1]: Starting linger-users.service... machine # [ 49.793637] systemd[1]: Reached target Local File Systems. machine # [ 49.806329] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 49.811291] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 49.815000] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 49.824296] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 49.826639] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 49.830768] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 49.833410] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 49.900568] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 49.904302] systemd[1]: Finished linger-users.service. machine # [ 50.198647] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 50.201837] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 50.460416] nixos[2038]: finished switching to system configuration /nix/store/pshfghz8n8vb36ryvh6ydxvz2gkqfzj0-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 3.25 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 50.549416] sudo[2177]: 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 # [ 50.567082] sudo[2177]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 50.581985] sudo[2177]: 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.11 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1 machine # [ 51.125192] nixos[2186]: switching to system configuration /nix/store/zcrywg5wyj4g3ilqa3j99jrvi5vbjzzk-nixos-system-machine-test machine # [ 51.128315] systemd[1]: Stopped target Local File Systems. machine # [ 51.129711] systemd[1]: Stopped target Remote File Systems. machine # [ 51.633072] systemd[1]: Reload requested from client PID 2186 ('.switch-to-conf') (unit backdoor.service)... machine # [ 51.635044] systemd[1]: Reloading... machine # [ 52.206102] systemd[1]: Reloading finished in 568 ms. machine # [ 52.378476] systemd[584]: Reexecution requested from client PID 2255 ('switch-to-confi')... machine # [ 52.382590] systemd[584]: Reexecuting. machine # [ 52.445988] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.650992] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.658982] systemd[584]: Reload requested from client PID 2255 ('switch-to-confi')... machine # [ 52.660780] systemd[584]: Reloading... machine # [ 52.849816] systemd[584]: Reloading finished in 187 ms. machine # [ 52.865652] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.874399] systemd[584]: Reloading usertest.service... machine # [ 52.911958] systemd[584]: Reloaded usertest.service. machine # [ 52.917630] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 52.920291] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 52.923057] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 52.970705] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 52.975270] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 52.977277] systemd[1]: Stopping Reactivate sysinit units... machine # [ 52.978138] systemd[1]: Reached target Reactivate sysinit units. machine # [ 52.979555] systemd[1]: Reached target Remote File Systems. machine # [ 52.982254] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 52.984686] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 52.989002] systemd[1]: Reached target Local File Systems. machine # [ 52.989847] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 52.995966] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 52.997482] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 52.998706] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 53.001976] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 53.004969] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.014450] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.339881] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 53.344949] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 53.347255] systemd[1]: Starting linger-users.service... machine # [ 53.410206] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 53.412134] systemd[1]: Finished linger-users.service. machine # [ 53.669935] nixos[2186]: finished switching to system configuration /nix/store/zcrywg5wyj4g3ilqa3j99jrvi5vbjzzk-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 3.09 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 53.741281] sudo[2325]: 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 # [ 53.760895] sudo[2325]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 53.775392] sudo[2325]: 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.10 seconds) machine: must fail: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1 machine # [ 54.333840] nixos[2334]: switching to system configuration /nix/store/rf8f0cn1k0z7ax1wdy9nmfln17hafc3w-nixos-system-machine-test machine # [ 54.336918] systemd[1]: Stopped target Remote File Systems. machine # [ 54.339623] systemd[1]: Stopped target Local File Systems. machine # [ 54.847517] systemd[1]: Reload requested from client PID 2334 ('.switch-to-conf') (unit backdoor.service)... machine # [ 54.849994] systemd[1]: Reloading... machine # [ 55.462223] systemd[1]: Reloading finished in 608 ms. machine # [ 55.638923] systemd[584]: Stopped usertest.service. machine # [ 55.642926] systemd[584]: Reexecution requested from client PID 2403 ('switch-to-confi')... machine # [ 55.645610] systemd[584]: Reexecuting. machine # [ 55.715138] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.935567] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.943371] systemd[584]: Reload requested from client PID 2403 ('switch-to-confi')... machine # [ 55.947646] systemd[584]: Reloading... machine # [ 56.147758] systemd[584]: Reloading finished in 201 ms. machine # [ 56.160189] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 56.171923] systemd[584]: Starting usertest.service... machine # [ 56.214921] systemd[584]: usertest.service: Main process exited, code=exited, status=1/FAILURE machine # [ 56.217889] systemd[584]: usertest.service: Failed with result 'exit-code'. machine # [ 56.219283] systemd[584]: Failed to start usertest.service. machine # [ 56.223822] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 56.225228] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 56.227846] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 56.277188] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 56.282221] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 56.283742] systemd[1]: Stopping Reactivate sysinit units... machine # [ 56.286393] systemd[1]: Reached target Reactivate sysinit units. machine # [ 56.291977] systemd[1]: Reached target Remote File Systems. machine # [ 56.295848] systemd[1]: Reached target Local File Systems. machine # [ 56.299873] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 56.304341] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 56.305347] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 56.306586] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 56.315029] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 56.316977] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 56.318686] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 56.320103] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 56.324277] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 56.709217] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 56.712652] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 56.718402] systemd[1]: Starting linger-users.service... machine # [ 56.789172] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 56.792256] systemd[1]: Finished linger-users.service. machine # [ 57.051240] nixos[2334]: switching to system configuration /nix/store/rf8f0cn1k0z7ax1wdy9nmfln17hafc3w-nixos-system-machine-test failed (status 4) machine: (finished: must fail: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 3.29 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 57.691470] nixos[2476]: switching to system configuration /nix/store/pshfghz8n8vb36ryvh6ydxvz2gkqfzj0-nixos-system-machine-test machine # [ 57.694994] systemd[1]: Stopped target Local File Systems. machine # [ 57.696757] systemd[1]: Stopped target Remote File Systems. machine # [ 58.159310] systemd[1]: Reload requested from client PID 2476 ('.switch-to-conf') (unit backdoor.service)... machine # [ 58.161589] systemd[1]: Reloading... machine # [ 58.759067] systemd[1]: Reloading finished in 595 ms. machine # [ 58.909813] systemd[584]: Reexecution requested from client PID 2544 ('switch-to-confi')... machine # [ 58.911305] systemd[584]: Reexecuting. machine # [ 58.983149] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.186284] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.193398] systemd[584]: Reload requested from client PID 2544 ('switch-to-confi')... machine # [ 59.195325] systemd[584]: Reloading... machine # [ 59.393838] systemd[584]: Reloading finished in 198 ms. machine # [ 59.409733] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.419952] systemd[584]: Starting usertest.service... machine # [ 59.456637] systemd[584]: Finished usertest.service. machine # [ 59.460126] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 59.461341] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 59.465440] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 59.514599] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 59.518982] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 59.521992] systemd[1]: Stopping Reactivate sysinit units... machine # [ 59.522872] systemd[1]: Reached target Reactivate sysinit units. machine # [ 59.523720] systemd[1]: Reached target Remote File Systems. machine # [ 59.529743] systemd[1]: Reached target Local File Systems. machine # [ 59.530935] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 59.534759] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 59.535618] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 59.539526] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 59.542547] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 59.549343] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 59.550619] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 59.558560] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 59.561767] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 59.899294] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 59.902181] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 59.907899] systemd[1]: Starting linger-users.service... machine # [ 59.979408] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 59.982443] systemd[1]: Finished linger-users.service. machine # [ 60.240930] nixos[2476]: finished switching to system configuration /nix/store/pshfghz8n8vb36ryvh6ydxvz2gkqfzj0-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 3.19 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 60.328756] sudo[2615]: 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 # [ 60.349067] sudo[2615]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 60.364730] sudo[2615]: 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.11 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 60.930641] nixos[2624]: switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test machine # [ 60.933417] systemd[1]: Stopped target Remote File Systems. machine # [ 60.935741] systemd[1]: Stopped target Local File Systems. machine # [ 61.404430] systemd[1]: Reload requested from client PID 2624 ('.switch-to-conf') (unit backdoor.service)... machine # [ 61.406622] systemd[1]: Reloading... machine # [ 62.021866] systemd[1]: Reloading finished in 612 ms. machine # [ 62.180365] systemd[584]: Stopped usertest.service. machine # [ 62.184237] systemd[584]: Reexecution requested from client PID 2693 ('switch-to-confi')... machine # [ 62.186453] systemd[584]: Reexecuting. machine # [ 62.246222] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.442542] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.449792] systemd[584]: Reload requested from client PID 2693 ('switch-to-confi')... machine # [ 62.452262] systemd[584]: Reloading... machine # [ 62.647691] systemd[584]: Reloading finished in 194 ms. machine # [ 62.660163] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.670380] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 62.671514] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 62.674511] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 62.722517] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 62.726776] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 62.727777] systemd[1]: Stopping Reactivate sysinit units... machine # [ 62.730829] systemd[1]: Reached target Reactivate sysinit units. machine # [ 62.736078] systemd[1]: Reached target Local File Systems. machine # [ 62.739683] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 62.744096] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 62.744987] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 62.746169] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 62.758268] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 62.762735] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.764241] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.765443] systemd[1]: Reached target Remote File Systems. machine # [ 62.766241] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.767512] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 63.101466] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 63.104152] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 63.109842] systemd[1]: Starting linger-users.service... machine # [ 63.179007] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 63.181907] systemd[1]: Finished linger-users.service. machine # [ 63.441879] nixos[2624]: finished switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.09 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 63.533972] sudo[2761]: 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 # [ 63.554862] sudo[2761]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 63.573992] sudo[2761]: 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.12 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 63.630884] sudo[2767]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 63.646372] sudo[2767]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 63.660668] sudo[2767]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.09 seconds) machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 63.711033] sudo[2773]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 63.725776] sudo[2773]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 63.741666] sudo[2773]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.08 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 63.791991] sudo[2779]: 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 # [ 63.806913] sudo[2779]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 63.821710] sudo[2779]: 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.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 63.871362] sudo[2785]: 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 # [ 63.885245] sudo[2785]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 63.896245] systemd[584]: Reload requested from client PID 2790 ('systemctl')... machine # [ 63.898607] systemd[584]: Reloading... machine # [ 64.103207] systemd[584]: Reloading finished in 202 ms. machine # [ 64.116232] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 64.131249] sudo[2785]: 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.31 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 64.183862] sudo[2795]: 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 # [ 64.198590] sudo[2795]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 64.217501] systemd[584]: Starting migrated.service... machine # [ 64.262099] systemd[584]: Finished migrated.service. machine # [ 64.267103] sudo[2795]: 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.14 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 64.318869] sudo[2803]: 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 # [ 64.334049] sudo[2803]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 64.348119] sudo[2803]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 64.400993] sudo[2809]: 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 # [ 64.415295] sudo[2809]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 64.430585] sudo[2809]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 64.479902] sudo[2815]: 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 # [ 64.494615] sudo[2815]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 64.519930] sudo[2815]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.09 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1 machine # [ 65.083411] nixos[2824]: switching to system configuration /nix/store/mnl5fks2p8jdjjd37njx5fj01z5i2yjg-nixos-system-machine-test machine # [ 65.086908] systemd[1]: Stopped target Local File Systems. machine # [ 65.088353] systemd[1]: Stopped target Remote File Systems. machine # [ 65.578500] systemd[1]: Reload requested from client PID 2824 ('.switch-to-conf') (unit backdoor.service)... machine # [ 65.580657] systemd[1]: Reloading... machine # [ 66.162053] systemd[1]: Reloading finished in 579 ms. machine # [ 66.339058] systemd[584]: Reexecution requested from client PID 2893 ('switch-to-confi')... machine # [ 66.343220] systemd[584]: Reexecuting. machine # [ 66.399208] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 66.612290] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 66.619505] systemd[584]: Reload requested from client PID 2893 ('switch-to-confi')... machine # [ 66.624167] systemd[584]: Reloading... machine # [ 66.828142] systemd[584]: Reloading finished in 204 ms. machine # [ 66.844487] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 66.855192] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 66.858003] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 66.861218] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 66.963859] systemd[584]: Reload requested from client PID 2905 ('systemctl') (unit nixos-activation.service)... machine # [ 66.969274] systemd[584]: Reloading... machine # [ 67.182359] systemd[584]: Reloading finished in 214 ms. machine # [ 67.196202] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 67.225035] systemd[584]: Stopped migrated.service. machine # [ 67.239960] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 67.243225] systemd[584]: Reload requested from client PID 2893 ('switch-to-confi')... machine # [ 67.248325] systemd[584]: Reloading... machine # [ 67.452579] systemd[584]: Reloading finished in 205 ms. machine # [ 67.468190] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 67.482551] systemd[584]: Starting migrated.service... machine # [ 67.523889] systemd[584]: Finished migrated.service. machine # [ 67.529054] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 67.533067] systemd[1]: Stopping Reactivate sysinit units... machine # [ 67.533979] systemd[1]: Reached target Reactivate sysinit units. machine # [ 67.534861] systemd[1]: Reached target Local File Systems. machine # [ 67.537887] systemd[1]: Reached target Remote File Systems. machine # [ 67.542335] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 67.550230] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 67.554048] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 67.555373] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 67.565664] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 67.567325] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 67.569203] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 67.570436] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 67.571738] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 67.913046] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 67.918115] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 67.921164] systemd[1]: Starting linger-users.service... machine # [ 67.988810] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 67.991581] systemd[1]: Finished linger-users.service. machine # [ 68.251265] nixos[2824]: finished switching to system configuration /nix/store/mnl5fks2p8jdjjd37njx5fj01z5i2yjg-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 3.74 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 68.340868] sudo[2975]: 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 # [ 68.361371] sudo[2975]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 68.376824] sudo[2975]: 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.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 68.431459] sudo[2981]: 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 # [ 68.446381] sudo[2981]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 68.477119] sudo[2981]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.10 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 68.528295] sudo[2987]: 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 # [ 68.541911] sudo[2987]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 68.554514] sudo[2987]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 69.083697] nixos[2996]: switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test machine # [ 69.086437] systemd[1]: Stopped target Remote File Systems. machine # [ 69.088836] systemd[1]: Stopped target Local File Systems. machine # [ 69.543908] systemd[1]: Reload requested from client PID 2996 ('.switch-to-conf') (unit backdoor.service)... machine # [ 69.546205] systemd[1]: Reloading... machine # [ 70.150971] systemd[1]: Reloading finished in 602 ms. machine # [ 70.303968] systemd[584]: Stopped migrated.service. machine # [ 70.307225] systemd[584]: Reexecution requested from client PID 3065 ('switch-to-confi')... machine # [ 70.309954] systemd[584]: Reexecuting. machine # [ 70.378746] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 70.573075] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 70.579585] systemd[584]: Reload requested from client PID 3065 ('switch-to-confi')... machine # [ 70.583637] systemd[584]: Reloading... machine # [ 70.777424] systemd[584]: Reloading finished in 194 ms. machine # [ 70.792190] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 70.801747] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 70.804138] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 70.807221] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 70.853943] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 70.858030] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 70.859992] systemd[1]: Stopping Reactivate sysinit units... machine # [ 70.861484] systemd[1]: Reached target Reactivate sysinit units. machine # [ 70.863499] systemd[1]: Reached target Local File Systems. machine # [ 70.868593] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 70.872279] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 70.873211] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 70.874419] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 70.888380] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 70.892817] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 70.894168] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 70.895379] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 70.896967] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 70.898253] systemd[1]: Reached target Remote File Systems. machine # [ 71.238318] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 71.242886] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 71.245585] systemd[1]: Starting linger-users.service... machine # [ 71.310909] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 71.313936] systemd[1]: Finished linger-users.service. machine # [ 71.570885] nixos[2996]: finished switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.03 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 71.657695] sudo[3133]: 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 # [ 71.676862] sudo[3133]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 71.692969] sudo[3133]: 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.11 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 71.744713] sudo[3139]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 71.759216] sudo[3139]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 71.773946] sudo[3139]: 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.08 seconds) machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 71.823115] sudo[3145]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 71.838482] sudo[3145]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 71.851841] sudo[3145]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.08 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 71.899256] sudo[3151]: 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 # [ 71.914099] sudo[3151]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 71.927236] sudo[3151]: 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.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 71.975923] sudo[3157]: 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 # [ 71.989643] sudo[3157]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 72.000514] systemd[584]: Reload requested from client PID 3162 ('systemctl')... machine # [ 72.003160] systemd[584]: Reloading... machine # [ 72.198882] systemd[584]: Reloading finished in 194 ms. machine # [ 72.213625] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 72.225742] sudo[3157]: 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.30 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 72.273371] sudo[3167]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service machine # [ 72.286827] sudo[3167]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 72.303753] systemd[584]: Starting migrated.service... machine # [ 72.338254] systemd[584]: Finished migrated.service. machine # [ 72.342664] sudo[3167]: 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.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 72.391867] sudo[3175]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 72.405759] sudo[3175]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 72.418288] sudo[3175]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 72.466750] sudo[3181]: 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 # [ 72.480769] sudo[3181]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 72.493723] sudo[3181]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 72.540763] sudo[3187]: 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 # [ 72.554348] sudo[3187]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 72.577387] sudo[3187]: 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.08 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1 machine # [ 73.110703] nixos[3196]: switching to system configuration /nix/store/nwp1vqhdqmv4dkl7jq71c76m9skyrmc2-nixos-system-machine-test machine # [ 73.114856] systemd[1]: Stopped target Local File Systems. machine # [ 73.116109] systemd[1]: Stopped target Remote File Systems. machine # [ 73.618336] systemd[1]: Reload requested from client PID 3196 ('.switch-to-conf') (unit backdoor.service)... machine # [ 73.620430] systemd[1]: Reloading... machine # [ 74.213541] systemd[1]: Reloading finished in 589 ms. machine # [ 74.378512] systemd[584]: Reexecution requested from client PID 3264 ('switch-to-confi')... machine # [ 74.382170] systemd[584]: Reexecuting. machine # [ 74.445331] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 74.649590] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 74.657219] systemd[584]: Reload requested from client PID 3264 ('switch-to-confi')... machine # [ 74.660006] systemd[584]: Reloading... machine # [ 74.855398] systemd[584]: Reloading finished in 194 ms. machine # [ 74.872174] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 74.881285] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 74.883704] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 74.886738] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 74.947976] systemd[584]: Reload requested from client PID 3277 ('systemctl') (unit nixos-activation.service)... machine # [ 74.950738] systemd[584]: Reloading... machine # [ 75.155564] systemd[584]: Reloading finished in 203 ms. machine # [ 75.168180] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 75.187613] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 75.190659] systemd[584]: Reload requested from client PID 3264 ('switch-to-confi')... machine # [ 75.193367] systemd[584]: Reloading... machine # [ 75.393400] systemd[584]: Reloading finished in 198 ms. machine # [ 75.408191] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 75.418822] systemd[584]: Stopped migrated.service. machine # [ 75.420977] systemd[584]: Stopping migrated.service... machine # [ 75.423836] systemd[584]: Starting migrated.service... machine # [ 75.464322] systemd[584]: Finished migrated.service. machine # [ 75.471090] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 75.474098] systemd[1]: Stopping Reactivate sysinit units... machine # [ 75.475007] systemd[1]: Reached target Reactivate sysinit units. machine # [ 75.475863] systemd[1]: Reached target Remote File Systems. machine # [ 75.478899] systemd[1]: Reached target Local File Systems. machine # [ 75.482563] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 75.486985] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 75.487949] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 75.491495] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 75.498118] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 75.504852] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 75.506218] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 75.507456] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 75.511847] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 75.843644] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 75.848702] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 75.853150] systemd[1]: Starting linger-users.service... machine # [ 75.918865] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 75.921871] systemd[1]: Finished linger-users.service. machine # [ 76.178878] nixos[3196]: finished switching to system configuration /nix/store/nwp1vqhdqmv4dkl7jq71c76m9skyrmc2-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 3.61 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 76.269923] sudo[3346]: 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 # [ 76.293204] sudo[3346]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 76.310639] sudo[3346]: 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.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 76.362911] sudo[3352]: 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 # [ 76.378266] sudo[3352]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 76.406309] sudo[3352]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.10 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 76.455296] sudo[3358]: 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 # [ 76.470417] sudo[3358]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 76.483826] sudo[3358]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 77.033058] nixos[3367]: switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test machine # [ 77.035860] systemd[1]: Stopped target Remote File Systems. machine # [ 77.038266] systemd[1]: Stopped target Local File Systems. machine # [ 77.501734] systemd[1]: Reload requested from client PID 3367 ('.switch-to-conf') (unit backdoor.service)... machine # [ 77.503745] systemd[1]: Reloading... machine # [ 78.074602] systemd[1]: Reloading finished in 568 ms. machine # [ 78.218842] systemd[584]: Stopped migrated.service. machine # [ 78.222763] systemd[584]: Reexecution requested from client PID 3436 ('switch-to-confi')... machine # [ 78.226401] systemd[584]: Reexecuting. machine # [ 78.281803] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 78.462621] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 78.469939] systemd[584]: Reload requested from client PID 3436 ('switch-to-confi')... machine # [ 78.472444] systemd[584]: Reloading... machine # [ 78.655571] systemd[584]: Reloading finished in 182 ms. machine # [ 78.672524] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 78.683675] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 78.686356] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 78.689354] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 78.737972] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 78.742641] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 78.744163] systemd[1]: Stopping Reactivate sysinit units... machine # [ 78.745007] systemd[1]: Reached target Reactivate sysinit units. machine # [ 78.747973] systemd[1]: Reached target Remote File Systems. machine # [ 78.753314] systemd[1]: Reached target Local File Systems. machine # [ 78.756846] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 78.758228] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 78.759480] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 78.765133] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 78.766111] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 78.767312] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 78.772936] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 78.782289] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 78.783709] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 79.113432] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 79.118193] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 79.121030] systemd[1]: Starting linger-users.service... machine # [ 79.186435] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 79.188524] systemd[1]: Finished linger-users.service. machine # [ 79.450651] nixos[3367]: finished switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.98 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 79.549142] sudo[3504]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 79.569557] sudo[3504]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 79.587067] sudo[3504]: 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.12 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 79.637663] sudo[3510]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 79.651251] sudo[3510]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 79.663448] sudo[3510]: 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.08 seconds) machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 79.711072] sudo[3516]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 79.726157] sudo[3516]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 79.739727] sudo[3516]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.08 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 79.789668] sudo[3522]: 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 # [ 79.805049] sudo[3522]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 79.818524] sudo[3522]: 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.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 79.871145] sudo[3528]: 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 # [ 79.885707] sudo[3528]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 79.898155] systemd[584]: Reload requested from client PID 3533 ('systemctl')... machine # [ 79.901317] systemd[584]: Reloading... machine # [ 80.102972] systemd[584]: Reloading finished in 200 ms. machine # [ 80.116221] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 80.128058] sudo[3528]: 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.31 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 80.179399] sudo[3538]: 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 # [ 80.194077] sudo[3538]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 80.211206] systemd[584]: Starting migrated.service... machine # [ 80.247471] systemd[584]: Finished migrated.service. machine # [ 80.252506] sudo[3538]: 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.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 80.301034] sudo[3546]: 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 # [ 80.314849] sudo[3546]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 80.328089] sudo[3546]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 80.379287] sudo[3552]: 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 # [ 80.393168] sudo[3552]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 80.406461] sudo[3552]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 80.455386] sudo[3558]: 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 # [ 80.469934] sudo[3558]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 80.493398] sudo[3558]: 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.09 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1 machine # [ 81.043673] nixos[3567]: switching to system configuration /nix/store/i1m50dqv1p2ykm1057rmb6ssfin360f2-nixos-system-machine-test machine # [ 81.047571] systemd[1]: Stopped target Local File Systems. machine # [ 81.049068] systemd[1]: Stopped target Remote File Systems. machine # [ 81.586711] systemd[1]: Reload requested from client PID 3567 ('.switch-to-conf') (unit backdoor.service)... machine # [ 81.588941] systemd[1]: Reloading... machine # [ 82.177154] systemd[1]: Reloading finished in 585 ms. machine # [ 82.350823] systemd[584]: Reexecution requested from client PID 3636 ('switch-to-confi')... machine # [ 82.353603] systemd[584]: Reexecuting. machine # [ 82.418269] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 82.626716] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 82.633910] systemd[584]: Reload requested from client PID 3636 ('switch-to-confi')... machine # [ 82.636796] systemd[584]: Reloading... machine # [ 82.834872] systemd[584]: Reloading finished in 196 ms. machine # [ 82.848212] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 82.858478] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 82.859582] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 82.862709] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 82.924624] systemd[584]: Reload requested from client PID 3648 ('systemctl') (unit nixos-activation.service)... machine # [ 82.927645] systemd[584]: Reloading... machine # [ 83.133930] systemd[584]: Reloading finished in 204 ms. machine # [ 83.148227] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 83.170455] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 83.173670] systemd[584]: Reload requested from client PID 3636 ('switch-to-confi')... machine # [ 83.177120] systemd[584]: Reloading... machine # [ 83.387549] systemd[584]: Reloading finished in 209 ms. machine # [ 83.400274] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 83.414759] systemd[584]: Reloading migrated.service... machine # [ 83.455557] systemd[584]: Reloaded migrated.service. machine # [ 83.465865] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 83.466913] systemd[1]: Stopping Reactivate sysinit units... machine # [ 83.467796] systemd[1]: Reached target Reactivate sysinit units. machine # [ 83.470841] systemd[1]: Reached target Local File Systems. machine # [ 83.474819] systemd[1]: Reached target Remote File Systems. machine # [ 83.483711] systemd[1]: Starting linger-users.service... machine # [ 83.487307] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 83.498451] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 83.499476] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 83.503669] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 83.516454] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 83.518087] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 83.519363] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 83.523451] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 83.525517] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 83.598176] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 83.601897] systemd[1]: Finished linger-users.service. machine # [ 83.900470] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 83.904543] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 84.163004] nixos[3567]: finished switching to system configuration /nix/store/i1m50dqv1p2ykm1057rmb6ssfin360f2-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 3.68 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 84.258717] sudo[3717]: 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 # [ 84.278338] sudo[3717]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 84.293807] sudo[3717]: 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.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 84.347655] sudo[3723]: 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 # [ 84.362284] sudo[3723]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 84.375586] sudo[3723]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 84.947201] nixos[3732]: switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test machine # [ 84.951493] systemd[1]: Stopped target Local File Systems. machine # [ 84.952802] systemd[1]: Stopped target Remote File Systems. machine # [ 85.423664] systemd[1]: Reload requested from client PID 3732 ('.switch-to-conf') (unit backdoor.service)... machine # [ 85.425992] systemd[1]: Reloading... machine # [ 86.057640] systemd[1]: Reloading finished in 629 ms. machine # [ 86.221237] systemd[584]: Stopped migrated.service. machine # [ 86.224609] systemd[584]: Reexecution requested from client PID 3801 ('switch-to-confi')... machine # [ 86.228141] systemd[584]: Reexecuting. machine # [ 86.295000] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 86.506598] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 86.514036] systemd[584]: Reload requested from client PID 3801 ('switch-to-confi')... machine # [ 86.517137] systemd[584]: Reloading... machine # [ 86.717762] systemd[584]: Reloading finished in 200 ms. machine # [ 86.732207] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 86.743317] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 86.746022] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 86.749084] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 86.802073] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 86.806949] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 86.808487] systemd[1]: Stopping Reactivate sysinit units... machine # [ 86.809443] systemd[1]: Reached target Reactivate sysinit units. machine # [ 86.813406] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 86.816223] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 86.819175] systemd[1]: Reached target Local File Systems. machine # [ 86.823197] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 86.830933] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 86.831964] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 86.836447] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 86.844248] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 86.846013] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 86.847293] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 86.852680] systemd[1]: Reached target Remote File Systems. machine # [ 87.212851] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 87.216529] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 87.221220] systemd[1]: Starting linger-users.service... machine # [ 87.291686] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 87.293717] systemd[1]: Finished linger-users.service. machine # [ 87.552860] nixos[3732]: finished switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.19 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 87.645845] sudo[3869]: 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 # [ 87.670078] sudo[3869]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 87.691142] sudo[3869]: 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.13 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 87.747153] sudo[3875]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 87.762302] sudo[3875]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 87.776145] sudo[3875]: 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.08 seconds) machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 87.827580] sudo[3881]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 87.842464] sudo[3881]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 87.856628] sudo[3881]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.08 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 87.907006] sudo[3887]: 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 # [ 87.922089] sudo[3887]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 87.935486] sudo[3887]: 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.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 87.985124] sudo[3893]: 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 # [ 87.999366] sudo[3893]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 88.010511] systemd[584]: Reload requested from client PID 3898 ('systemctl')... machine # [ 88.013511] systemd[584]: Reloading... machine # [ 88.230147] systemd[584]: Reloading finished in 215 ms. machine # [ 88.248454] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 88.264198] sudo[3893]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.33 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 88.322964] sudo[3903]: 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 # [ 88.337493] sudo[3903]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 88.352510] systemd[584]: Starting migrated.service... machine # [ 88.396438] systemd[584]: Finished migrated.service. machine # [ 88.401638] sudo[3903]: 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.14 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 88.451702] sudo[3911]: 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 # [ 88.466565] sudo[3911]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 88.479862] sudo[3911]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 88.543664] sudo[3917]: 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 # [ 88.559719] sudo[3917]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 88.575134] sudo[3917]: 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.10 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 88.633122] sudo[3923]: 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 # [ 88.647632] sudo[3923]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 88.673201] sudo[3923]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.10 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1 machine # [ 89.251925] nixos[3932]: switching to system configuration /nix/store/h1gyn8j5j0cfh53kbpjdrqhjnlgnzk90-nixos-system-machine-test machine # [ 89.256343] systemd[1]: Stopped target Remote File Systems. machine # [ 89.257513] systemd[1]: Stopped target Local File Systems. machine # [ 89.814062] systemd[1]: Reload requested from client PID 3932 ('.switch-to-conf') (unit backdoor.service)... machine # [ 89.816159] systemd[1]: Reloading... machine # [ 90.464209] systemd[1]: Reloading finished in 645 ms. machine # [ 90.671265] systemd[584]: Reexecution requested from client PID 4001 ('switch-to-confi')... machine # [ 90.674502] systemd[584]: Reexecuting. machine # [ 90.748663] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 90.984238] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 90.991892] systemd[584]: Reload requested from client PID 4001 ('switch-to-confi')... machine # [ 90.994576] systemd[584]: Reloading... machine # [ 91.214919] systemd[584]: Reloading finished in 219 ms. machine # [ 91.232300] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 91.243969] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 91.245168] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 91.248686] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 91.320547] systemd[584]: Reload requested from client PID 4013 ('systemctl') (unit nixos-activation.service)... machine # [ 91.323505] systemd[584]: Reloading... machine # [ 91.562926] systemd[584]: Reloading finished in 237 ms. machine # [ 91.576549] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 91.599442] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 91.602729] systemd[584]: Reload requested from client PID 4001 ('switch-to-confi')... machine # [ 91.607241] systemd[584]: Reloading... machine # [ 91.823682] systemd[584]: Reloading finished in 217 ms. machine # [ 91.836601] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 91.855982] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 91.859741] systemd[1]: Stopping Reactivate sysinit units... machine # [ 91.861614] systemd[1]: Reached target Reactivate sysinit units. machine # [ 91.862524] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 91.863836] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 91.866390] systemd[1]: Reached target Local File Systems. machine # [ 91.868771] systemd[1]: Reached target Remote File Systems. machine # [ 91.874490] systemd[1]: Starting linger-users.service... machine # [ 91.892197] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 91.898517] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 91.899573] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 91.903697] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 91.907095] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 91.913397] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 91.916751] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 91.988754] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 91.993299] systemd[1]: Finished linger-users.service. machine # [ 92.317651] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 92.321165] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 92.580991] nixos[3932]: finished switching to system configuration /nix/store/h1gyn8j5j0cfh53kbpjdrqhjnlgnzk90-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 3.92 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 92.678930] sudo[4080]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 92.701300] sudo[4080]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 92.719570] sudo[4080]: 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.13 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 92.782235] sudo[4086]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 92.798036] sudo[4086]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 92.812560] sudo[4086]: 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.09 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 93.431070] nixos[4095]: switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test machine # [ 93.435604] systemd[1]: Stopped target Remote File Systems. machine # [ 93.436879] systemd[1]: Stopped target Local File Systems. machine # [ 93.933407] systemd[1]: Reload requested from client PID 4095 ('.switch-to-conf') (unit backdoor.service)... machine # [ 93.935461] systemd[1]: Reloading... machine # [ 94.551004] systemd[1]: Reloading finished in 612 ms. machine # [ 94.714501] systemd[584]: Stopped migrated.service. machine # [ 94.718568] systemd[584]: Reexecution requested from client PID 4164 ('switch-to-confi')... machine # [ 94.721371] systemd[584]: Reexecuting. machine # [ 94.795329] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 95.003868] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 95.011799] systemd[584]: Reload requested from client PID 4164 ('switch-to-confi')... machine # [ 95.015348] systemd[584]: Reloading... machine # [ 95.215693] systemd[584]: Reloading finished in 201 ms. machine # [ 95.228208] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 95.239441] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 95.242096] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 95.245436] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 95.298388] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 95.303357] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 95.305814] systemd[1]: Stopping Reactivate sysinit units... machine # [ 95.306673] systemd[1]: Reached target Reactivate sysinit units. machine # [ 95.311470] systemd[1]: Reached target Local File Systems. machine # [ 95.313500] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 95.317373] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 95.318311] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 95.319561] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 95.330740] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 95.334463] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 95.335745] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 95.343005] systemd[1]: Reached target Remote File Systems. machine # [ 95.345743] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 95.347926] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 95.655905] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 95.658315] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 95.663968] systemd[1]: Starting linger-users.service... machine # [ 95.729201] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 95.731765] systemd[1]: Finished linger-users.service. machine # [ 95.987251] nixos[4095]: finished switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.18 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 96.056823] sudo[4232]: 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 # [ 96.073835] sudo[4232]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 96.090530] sudo[4232]: 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.10 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user machine # [ 96.141231] sudo[4238]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user machine # [ 96.155501] sudo[4238]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 96.168466] sudo[4238]: 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.08 seconds) machine: must succeed: sudo -u usertest cp /nix/store/sjwrvqsv40pz3pq93zpd2s2k0jw0h8qf-migrated.service ~usertest/.local/share/systemd/user/migrated.service machine # [ 96.220997] sudo[4244]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/sjwrvqsv40pz3pq93zpd2s2k0jw0h8qf-migrated.service /home/usertest/.local/share/systemd/user/migrated.service machine # [ 96.233923] sudo[4244]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 96.248962] sudo[4244]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/sjwrvqsv40pz3pq93zpd2s2k0jw0h8qf-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 96.300954] sudo[4250]: 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 # [ 96.315915] sudo[4250]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 96.328275] systemd[584]: Reload requested from client PID 4255 ('systemctl')... machine # [ 96.330897] systemd[584]: Reloading... machine # [ 96.539622] systemd[584]: Reloading finished in 206 ms. machine # [ 96.556197] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 96.567025] sudo[4250]: 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.32 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 96.621404] sudo[4260]: 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 # [ 96.636732] sudo[4260]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 96.657874] systemd[584]: Starting migrated.service... machine # [ 96.697716] systemd[584]: Finished migrated.service. machine # [ 96.702535] sudo[4260]: 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.13 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 96.760321] sudo[4268]: 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 # [ 96.776792] sudo[4268]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 96.791902] sudo[4268]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.09 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 96.861154] sudo[4274]: 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 # [ 96.878611] sudo[4274]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 96.894538] sudo[4274]: 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.10 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 96.956916] sudo[4280]: 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 # [ 96.973157] sudo[4280]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 97.021736] sudo[4280]: 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.13 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 97.613288] nixos[4289]: switching to system configuration /nix/store/jnq1jwn05wdixs0h4xzdxbzq6gxg7syi-nixos-system-machine-test machine # [ 97.616128] systemd[1]: Stopped target Remote File Systems. machine # [ 97.618435] systemd[1]: Stopped target Local File Systems. machine # [ 98.175484] systemd[1]: Reload requested from client PID 4289 ('.switch-to-conf') (unit backdoor.service)... machine # [ 98.177676] systemd[1]: Reloading... machine # [ 98.810348] systemd[1]: Reloading finished in 630 ms. machine # [ 98.986537] systemd[584]: Reexecution requested from client PID 4358 ('switch-to-confi')... machine # [ 98.990255] systemd[584]: Reexecuting. machine # [ 99.030371] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 99.249670] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 99.256796] systemd[584]: Reload requested from client PID 4358 ('switch-to-confi')... machine # [ 99.259374] systemd[584]: Reloading... machine # [ 99.468879] systemd[584]: Reloading finished in 208 ms. machine # [ 99.484176] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 99.494080] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 99.496480] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 99.499644] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 99.549656] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 99.551762] systemd[584]: Reload requested from client PID 4358 ('switch-to-confi')... machine # [ 99.554664] systemd[584]: Reloading... machine # [ 99.759854] systemd[584]: Reloading finished in 204 ms. machine # [ 99.773618] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 99.784080] systemd[584]: Stopped migrated.service. machine # [ 99.784912] systemd[584]: Stopping migrated.service... machine # [ 99.787922] systemd[584]: Starting migrated.service... machine # [ 99.827938] systemd[584]: Finished migrated.service. machine # [ 99.834874] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 99.837434] systemd[1]: Stopping Reactivate sysinit units... machine # [ 99.838309] systemd[1]: Reached target Reactivate sysinit units. machine # [ 99.844774] systemd[1]: Reached target Local File Systems. machine # [ 99.847286] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 99.854681] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 99.855916] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 99.861496] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 99.863743] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 99.865948] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 99.867547] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 99.868982] systemd[1]: Reached target Remote File Systems. machine # [ 99.874158] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 99.875586] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 100.208892] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 100.211083] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 100.216386] systemd[1]: Starting linger-users.service... machine # [ 100.277098] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 100.279888] systemd[1]: Finished linger-users.service. machine # [ 100.539245] nixos[4289]: finished switching to system configuration /nix/store/jnq1jwn05wdixs0h4xzdxbzq6gxg7syi-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 3.53 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 100.627089] sudo[4432]: 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 # [ 100.647035] sudo[4432]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 100.662837] sudo[4432]: 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.11 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 100.713652] sudo[4438]: 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 # [ 100.728917] sudo[4438]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 100.759573] sudo[4438]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.10 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 100.808666] sudo[4444]: 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 # [ 100.821604] sudo[4444]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 100.834869] sudo[4444]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 101.360543] nixos[4453]: switching to system configuration /nix/store/jnq1jwn05wdixs0h4xzdxbzq6gxg7syi-nixos-system-machine-test machine # [ 101.363319] systemd[1]: Stopped target Remote File Systems. machine # [ 101.365375] systemd[1]: Stopped target Local File Systems. machine # [ 101.823990] systemd[1]: Reload requested from client PID 4453 ('.switch-to-conf') (unit backdoor.service)... machine # [ 101.826088] systemd[1]: Reloading... machine # [ 102.473131] systemd[1]: Reloading finished in 644 ms. machine # [ 102.640142] systemd[584]: Reexecution requested from client PID 4522 ('switch-to-confi')... machine # [ 102.644446] systemd[584]: Reexecuting. machine # [ 102.712451] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 102.941453] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 102.948364] systemd[584]: Reload requested from client PID 4522 ('switch-to-confi')... machine # [ 102.950690] systemd[584]: Reloading... machine # [ 103.158461] systemd[584]: Reloading finished in 206 ms. machine # [ 103.172221] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 103.183561] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 103.185894] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 103.188813] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 103.240996] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 103.245458] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 103.248970] systemd[1]: Stopping Reactivate sysinit units... machine # [ 103.250785] systemd[1]: Reached target Reactivate sysinit units. machine # [ 103.251721] systemd[1]: Reached target Remote File Systems. machine # [ 103.254079] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 103.257625] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 103.259413] systemd[1]: Reached target Local File Systems. machine # [ 103.265464] systemd[1]: Starting linger-users.service... machine # [ 103.270406] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 103.275752] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 103.277196] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 103.281500] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 103.283756] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 103.296111] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 103.297505] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 103.372424] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 103.375231] systemd[1]: Finished linger-users.service. machine # [ 103.691506] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 103.695464] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 103.951959] nixos[4453]: finished switching to system configuration /nix/store/jnq1jwn05wdixs0h4xzdxbzq6gxg7syi-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 3.13 seconds) machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd machine # [ 104.046025] sudo[4590]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd machine # [ 104.065696] sudo[4590]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 104.082148] sudo[4590]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 104.138866] sudo[4596]: 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 # [ 104.153778] sudo[4596]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 104.165326] systemd[584]: Reload requested from client PID 4601 ('systemctl')... machine # [ 104.169625] systemd[584]: Reloading... machine # [ 104.374723] systemd[584]: Reloading finished in 206 ms. machine # [ 104.388187] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 104.399705] sudo[4596]: 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.32 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 104.951778] nixos[4609]: switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test machine # [ 104.956050] systemd[1]: Stopped target Remote File Systems. machine # [ 104.957024] systemd[1]: Stopped target Local File Systems. machine # [ 105.473536] systemd[1]: Reload requested from client PID 4609 ('.switch-to-conf') (unit backdoor.service)... machine # [ 105.475667] systemd[1]: Reloading... machine # [ 106.108113] systemd[1]: Reloading finished in 629 ms. machine # [ 106.269927] systemd[584]: Stopped migrated.service. machine # [ 106.273811] systemd[584]: Reexecution requested from client PID 4678 ('switch-to-confi')... machine # [ 106.275196] systemd[584]: Reexecuting. machine # [ 106.338738] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 106.545494] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 106.552584] systemd[584]: Reload requested from client PID 4678 ('switch-to-confi')... machine # [ 106.555167] systemd[584]: Reloading... machine # [ 106.760962] systemd[584]: Reloading finished in 204 ms. machine # [ 106.776237] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 106.785990] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 106.788469] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 106.791761] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 106.841457] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 106.845546] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 106.848177] systemd[1]: Stopping Reactivate sysinit units... machine # [ 106.849129] systemd[1]: Reached target Reactivate sysinit units. machine # [ 106.853770] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 106.857315] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 106.862066] systemd[1]: Reached target Local File Systems. machine # [ 106.862892] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 106.867663] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 106.870070] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 106.872951] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 106.876526] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 106.878905] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 106.884395] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 106.885658] systemd[1]: Reached target Remote File Systems. machine # [ 107.270739] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 107.275310] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 107.278072] systemd[1]: Starting linger-users.service... machine # [ 107.344841] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 107.347852] systemd[1]: Finished linger-users.service. machine # [ 107.604806] nixos[4609]: finished switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.22 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 107.701002] sudo[4746]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 107.722912] sudo[4746]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 107.739122] sudo[4746]: 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.12 seconds) machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 107.794559] sudo[4752]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 107.809883] sudo[4752]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 107.824229] sudo[4752]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.08 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 107.873045] sudo[4758]: 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 # [ 107.888787] sudo[4758]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 107.901827] sudo[4758]: 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.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 107.951980] sudo[4764]: 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 # [ 107.966744] sudo[4764]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 107.977557] systemd[584]: Reload requested from client PID 4769 ('systemctl')... machine # [ 107.980285] systemd[584]: Reloading... machine # [ 108.197421] systemd[584]: Reloading finished in 216 ms. machine # [ 108.212221] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 108.226799] sudo[4764]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.33 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 108.283698] sudo[4774]: 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 # [ 108.300282] sudo[4774]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 108.317839] systemd[584]: Starting migrated.service... machine # [ 108.359920] systemd[584]: Finished migrated.service. machine # [ 108.365664] sudo[4774]: 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.14 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 108.420142] sudo[4782]: 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 # [ 108.434536] sudo[4782]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 108.448119] sudo[4782]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 108.507628] sudo[4788]: 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 # [ 108.523631] sudo[4788]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 108.538113] sudo[4788]: 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.09 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 108.591365] sudo[4794]: 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 # [ 108.606330] sudo[4794]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 108.630859] sudo[4794]: 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.09 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 109.256687] nixos[4803]: switching to system configuration /nix/store/jnq1jwn05wdixs0h4xzdxbzq6gxg7syi-nixos-system-machine-test machine # [ 109.259425] systemd[1]: Stopped target Remote File Systems. machine # [ 109.261714] systemd[1]: Stopped target Local File Systems. machine # [ 109.744982] systemd[1]: Reload requested from client PID 4803 ('.switch-to-conf') (unit backdoor.service)... machine # [ 109.747080] systemd[1]: Reloading... machine # [ 110.382881] systemd[1]: Reloading finished in 633 ms. machine # [ 110.534773] systemd[584]: Reexecution requested from client PID 4871 ('switch-to-confi')... machine # [ 110.538569] systemd[584]: Reexecuting. machine # [ 110.602847] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 110.802944] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 110.809469] systemd[584]: Reload requested from client PID 4871 ('switch-to-confi')... machine # [ 110.811743] systemd[584]: Reloading... machine # [ 111.005914] systemd[584]: Reloading finished in 192 ms. machine # [ 111.020258] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 111.031368] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 111.033951] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 111.036948] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 111.087466] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 111.089980] systemd[584]: Reload requested from client PID 4871 ('switch-to-confi')... machine # [ 111.092617] systemd[584]: Reloading... machine # [ 111.289472] systemd[584]: Reloading finished in 195 ms. machine # [ 111.304195] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 111.318737] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 111.321553] systemd[1]: Stopping Reactivate sysinit units... machine # [ 111.323309] systemd[1]: Reached target Reactivate sysinit units. machine # [ 111.327957] systemd[1]: Reached target Local File Systems. machine # [ 111.329307] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 111.332177] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 111.333075] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 111.334307] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 111.349212] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 111.355724] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 111.358034] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 111.359300] systemd[1]: Reached target Remote File Systems. machine # [ 111.361860] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 111.363399] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 111.691471] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 111.696527] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 111.698991] systemd[1]: Starting linger-users.service... machine # [ 111.762092] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 111.764266] systemd[1]: Finished linger-users.service. machine # [ 112.022673] nixos[4803]: finished switching to system configuration /nix/store/jnq1jwn05wdixs0h4xzdxbzq6gxg7syi-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 3.40 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 112.113665] sudo[4944]: 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 # [ 112.135501] sudo[4944]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 112.166243] sudo[4944]: 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.13 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 112.221379] sudo[4950]: 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 # [ 112.236919] sudo[4950]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 112.249580] sudo[4950]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds) machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd machine # [ 112.300650] sudo[4956]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd machine # [ 112.314316] sudo[4956]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 112.327841] sudo[4956]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 112.378684] sudo[4962]: 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 # [ 112.392707] sudo[4962]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 112.403564] systemd[584]: Reload requested from client PID 4967 ('systemctl')... machine # [ 112.405502] systemd[584]: Reloading... machine # [ 112.624161] systemd[584]: Reloading finished in 216 ms. machine # [ 112.640196] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 112.654612] sudo[4962]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.33 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service machine # [ 112.707761] sudo[4972]: 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 # [ 112.723003] sudo[4972]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 112.739374] systemd[584]: Stopped migrated.service. machine # [ 112.745188] sudo[4972]: 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.09 seconds) machine: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 113.309404] nixos[4981]: switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test machine # [ 113.312325] systemd[1]: Stopped target Remote File Systems. machine # [ 113.314930] systemd[1]: Stopped target Local File Systems. machine # [ 113.754925] systemd[1]: Reload requested from client PID 4981 ('.switch-to-conf') (unit backdoor.service)... machine # [ 113.757258] systemd[1]: Reloading... machine # [ 114.333963] systemd[1]: Reloading finished in 574 ms. machine # [ 114.476634] systemd[584]: Reexecution requested from client PID 5049 ('switch-to-confi')... machine # [ 114.480311] systemd[584]: Reexecuting. machine # [ 114.533933] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 114.725844] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 114.733396] systemd[584]: Reload requested from client PID 5049 ('switch-to-confi')... machine # [ 114.735924] systemd[584]: Reloading... machine # [ 114.922950] systemd[584]: Reloading finished in 186 ms. machine # [ 114.936163] systemd[584]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 114.945119] systemd[584]: Stopped Run user-specific NixOS activation. machine # [ 114.947475] systemd[584]: Stopping Run user-specific NixOS activation... machine # [ 114.950197] systemd[584]: Starting Run user-specific NixOS activation... machine # [ 114.996993] systemd[584]: Finished Run user-specific NixOS activation. machine # [ 115.001291] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 115.004130] systemd[1]: Stopping Reactivate sysinit units... machine # [ 115.004999] systemd[1]: Reached target Reactivate sysinit units. machine # [ 115.006946] systemd[1]: Reached target Remote File Systems. machine # [ 115.009938] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 115.012981] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 115.018502] systemd[1]: Reached target Local File Systems. machine # [ 115.023907] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 115.027732] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 115.030511] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 115.032752] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 115.035221] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 115.040275] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 115.041500] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 115.391192] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 115.396556] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 115.399309] systemd[1]: Starting linger-users.service... machine # [ 115.465379] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 115.467640] systemd[1]: Finished linger-users.service. machine # [ 115.726802] nixos[4981]: finished switching to system configuration /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test machine: (finished: must succeed: /nix/store/3kqgay0ljng90yj18nwy1iszzhf7p3mn-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.99 seconds) (finished: subtest: user services, in 85.26 seconds) (finished: run the VM test script, in 116.77 seconds) test script finished in 116.85s cleanup kill QemuMachine (pid 45) machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14) (finished: cleanup, in 0.17 seconds) additionally exposed symbols: machine, other, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh