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 # Formatting '/build/vm-state-machine/tmp.QK7KlHQkAd', fmt=raw size=1073741824 machine: QEMU running (pid 45) 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: de6f5adc-a7c2-4a41-976a-d69787b518d5 machine # Superblock backups stored on blocks: machine # 32768, 98304, 163840, 229376 machine # machine # Allocating group tables: 0/8 done machine # Writing inode tables: 0/8 done machine # Creating journal (8192 blocks): done machine # Writing superblocks and filesystem accounting information: 0/8 done machine # machine # Virtualisation disk image created. machine # Starting virtiofs daemons... machine # [2026-09-27T03:13:14Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-27T03:13:14Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-27T03:13:14Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-27T03:13:14Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-27T03:13:14Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-27T03:13:14Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-27T03:13:14Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-27T03:13:14Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-27T03:13:14Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-27T03:13:14Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-27T03:13:14Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-27T03:13:14Z INFO virtiofsd] Client connected, servicing requests machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] machine # [ 0.000000] Linux version 6.18.53 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 21 13:06:06 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 0x7fdee700-0x7fdf1e7f] 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 s186776 r8192 d116328 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/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/init regInfo=/nix/.ro-store/csmdccsr9xahibh6dac2xmi6f93av4jh-closure-info/registration console=ttyAMA0,115200n8 console=tty0 machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/csmdccsr9xahibh6dac2xmi6f93av4jh-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 74963 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.000033] arm-pv: using stolen time PV machine # [ 0.000444] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.000611] Console: colour dummy device 80x25 machine # [ 0.000619] printk: legacy console [tty0] enabled machine # [ 0.000841] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) machine # [ 0.000848] pid_max: default: 32768 minimum: 301 machine # [ 0.000926] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.001063] landlock: Up and running. machine # [ 0.001066] Yama: becoming mindful. machine # [ 0.001582] LSM support for eBPF active machine # [ 0.001700] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.001720] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.003772] rcu: Hierarchical SRCU implementation. machine # [ 0.003778] rcu: Max phase no-delay instances is 1000. machine # [ 0.005089] fsl-mc MSI: its@8080000 domain created machine # [ 0.005211] EFI services will not be available. machine # [ 0.005313] smp: Bringing up secondary CPUs ... machine # [ 0.005321] smp: Brought up 1 node, 1 CPU machine # [ 0.005324] SMP: Total of 1 processors activated. machine # [ 0.005327] CPU: All CPU(s) started at EL1 machine # [ 0.005340] CPU features: detected: Branch Target Identification machine # [ 0.005345] CPU features: detected: ARMv8.4 Translation Table Level machine # [ 0.005348] CPU features: detected: Instruction cache invalidation not required for I/D coherence machine # [ 0.005352] CPU features: detected: Data cache clean to the PoU not required for I/D coherence machine # [ 0.005356] CPU features: detected: Common not Private translations machine # [ 0.005359] CPU features: detected: CRC32 instructions machine # [ 0.005362] CPU features: detected: Data cache clean to Point of Deep Persistence machine # [ 0.005365] CPU features: detected: Data cache clean to Point of Persistence machine # [ 0.005368] CPU features: detected: Data independent timing control (DIT) machine # [ 0.005371] CPU features: detected: E0PD machine # [ 0.005373] CPU features: detected: Enhanced Counter Virtualization machine # [ 0.005376] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.005380] CPU features: detected: Enhanced Virtualization Traps machine # [ 0.005383] CPU features: detected: Fine Grained Traps machine # [ 0.005387] CPU features: detected: Generic authentication (architected QARMA5 algorithm) machine # [ 0.005391] CPU features: detected: RCpc load-acquire (LDAPR) machine # [ 0.005394] CPU features: detected: LSE atomic instructions machine # [ 0.005397] CPU features: detected: Privileged Access Never machine # [ 0.005400] CPU features: detected: PMUv3 machine # [ 0.005403] CPU features: detected: RAS Extension Support machine # [ 0.005406] CPU features: detected: RASv1p1 Extension Support machine # [ 0.005409] CPU features: detected: Random Number Generator machine # [ 0.005411] CPU features: detected: Speculation barrier (SB) machine # [ 0.005414] CPU features: detected: Stage-2 Force Write-Back machine # [ 0.005417] CPU features: detected: TLB range maintenance instructions machine # [ 0.005421] CPU features: detected: Speculative Store Bypassing Safe (SSBS) machine # [ 0.005458] alternatives: applying system-wide alternatives machine # [ 0.008658] CPU features: detected: BBM Level 2 without TLB conflict abort machine # [ 0.008799] Memory: 893724K/1048576K available (24448K kernel code, 7094K rwdata, 26592K rodata, 4736K init, 1107K bss, 113536K reserved, 32768K cma-reserved) machine # [ 0.009158] devtmpfs: initialized machine # [ 0.010961] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.010982] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.011166] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL machine # [ 0.011170] 0 pages in range for non-PLT usage machine # [ 0.011172] 508224 pages in range for PLT usage machine # [ 0.011269] pinctrl core: initialized pinctrl subsystem machine # [ 0.012129] DMI not present or invalid. machine # [ 0.015423] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.017861] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.018102] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.018270] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.018295] audit: initializing netlink subsys (disabled) machine # [ 0.018943] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.018946] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.018949] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.018952] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.018955] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.018990] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 machine # [ 0.018998] cpuidle: using governor ladder machine # [ 0.019004] cpuidle: using governor menu machine # [ 0.019214] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.019231] ASID allocator initialised with 65536 entries machine # [ 0.020463] Serial: AMBA PL011 UART driver machine # [ 0.026153] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 machine # [ 0.026289] printk: console [ttyAMA0] enabled machine # [ 0.160866] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.160876] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.160880] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages machine # [ 0.160883] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.160886] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.160889] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.160892] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages machine # [ 0.160895] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.162187] fbcon: Taking over console machine # [ 0.162197] ACPI: Interpreter disabled. machine # [ 0.172425] iommu: Default domain type: Translated machine # [ 0.172432] iommu: DMA domain TLB invalidation policy: strict mode machine # [ 0.172834] SCSI subsystem initialized machine # [ 0.175022] usbcore: registered new interface driver usbfs machine # [ 0.175053] usbcore: registered new interface driver hub machine # [ 0.175070] usbcore: registered new device driver usb machine # [ 0.175378] pps_core: LinuxPPS API ver. 1 registered machine # [ 0.175381] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti machine # [ 0.175389] PTP clock support registered machine # [ 0.175437] EDAC MC: Ver: 3.0.0 machine # [ 0.175637] scmi_core: SCMI protocol bus registered machine # [ 0.182494] FPGA manager framework machine # [ 0.183167] vgaarb: loaded machine # [ 0.186236] clocksource: Switched to clocksource arch_sys_counter machine # [ 0.189997] VFS: Disk quotas dquot_6.6.0 machine # [ 0.190030] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.193922] netfs: FS-Cache loaded machine # [ 0.194137] pnp: PnP ACPI: disabled machine # [ 0.199108] NET: Registered PF_INET protocol family machine # [ 0.199265] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.231365] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.231409] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.231434] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.234788] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.234864] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.234956] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.234988] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.235011] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.235101] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.235120] NET: Registered PF_XDP protocol family machine # [ 0.235137] PCI: CLS 0 bytes, default 64 machine # [ 0.235401] Trying to unpack rootfs image as initramfs... machine # [ 0.247485] kvm [1]: HYP mode not available machine # [ 0.348067] Initialise system trusted keyrings machine # [ 0.348377] workingset: timestamp_bits=42 max_order=18 bucket_order=0 machine # [ 0.348748] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.348794] 9p: Installing v9fs 9p2000 file system support machine # [ 0.372040] Key type asymmetric registered machine # [ 0.372053] Asymmetric key parser 'x509' registered machine # [ 0.372145] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.383546] io scheduler mq-deadline registered machine # [ 0.383559] io scheduler kyber registered machine # [ 0.395633] pl061_gpio 9030000.pl061: PL061 GPIO chip registered machine # [ 0.396395] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.396827] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: machine # [ 0.396842] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 machine # [ 0.396852] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 machine # [ 0.396858] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 machine # [ 0.396882] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits machine # [ 0.396910] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.397003] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.397010] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.397014] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.397017] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.397020] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] machine # [ 0.397120] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.397616] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.397813] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.397825] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.397851] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.397863] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.398434] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.398624] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.398635] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.398661] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.399156] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.399349] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] machine # [ 0.399361] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.399387] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.425156] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.425344] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.425355] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.425381] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.425393] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.425886] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.426090] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.426116] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.426601] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.426791] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.426819] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.427226] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.427419] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] machine # [ 0.443763] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.443970] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.443999] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.444491] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.444685] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.444711] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.445187] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.445380] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.445406] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.445894] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.446195] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.446207] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.446235] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.446730] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.446919] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.446932] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.446959] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.467638] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.467647] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.467651] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.467700] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.467744] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned machine # [ 0.467790] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned machine # [ 0.467835] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.467886] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.467933] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.467976] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned machine # [ 0.468020] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned machine # [ 0.468065] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned machine # [ 0.468162] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.468209] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.468226] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.468243] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned machine # [ 0.468261] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned machine # [ 0.468278] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned machine # [ 0.468298] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned machine # [ 0.468317] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned machine # [ 0.468335] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned machine # [ 0.468360] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned machine # [ 0.468379] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned machine # [ 0.468397] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned machine # [ 0.468416] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned machine # [ 0.468433] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned machine # [ 0.468450] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned machine # [ 0.468469] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned machine # [ 0.468487] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned machine # [ 0.468504] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned machine # [ 0.468523] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned machine # [ 0.468545] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] machine # [ 0.468550] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] machine # [ 0.468553] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] machine # [ 0.469456] pci 0000:00:07.0: enabling device (0000 -> 0002) machine # [ 0.530451] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) machine # [ 0.533879] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) machine # [ 0.544161] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) machine # [ 0.546468] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) machine # [ 0.550636] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) machine # [ 0.553902] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) machine # [ 0.564936] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.566884] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) machine # [ 0.577214] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.579242] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) machine # [ 0.583420] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) machine # [ 0.596125] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.601452] msm_serial: driver initialized machine # [ 0.601612] SuperH (H)SCI(F) driver initialized machine # [ 0.601669] STM32 USART driver initialized machine # [ 0.638629] loop: module loaded machine # [ 0.638861] virtio_blk virtio2: 1/0/0 default/read/poll queues machine # [ 0.641028] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 0.648266] megasas: 07.734.00.00-rc1 machine # [ 0.649048] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] machine # [ 0.659547] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.659579] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.662174] Using buffer write method machine # [ 0.662234] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] machine # [ 0.667411] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.667431] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.676622] Using buffer write method machine # [ 0.676652] Concatenating MTD devices: machine # [ 0.676654] (0): "0.flash" machine # [ 0.676657] (1): "0.flash" machine # [ 0.676659] into device "0.flash" machine # [ 0.961999] Freeing initrd memory: 26424K machine # [ 0.968339] tun: Universal TUN/TAP device driver, 1.6 machine # [ 0.972531] thunder_xcv, ver 1.0 machine # [ 0.972560] thunder_bgx, ver 1.0 machine # [ 0.972603] nicpf, ver 1.0 machine # [ 0.973183] e1000: Intel(R) PRO/1000 Network Driver machine # [ 0.973187] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 0.973211] e1000e: Intel(R) PRO/1000 Network Driver machine # [ 0.973216] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. machine # [ 0.973244] igb: Intel(R) Gigabit Ethernet Network Driver machine # [ 0.973247] igb: Copyright (c) 2007-2014 Intel Corporation. machine # [ 0.973268] igbvf: Intel(R) Gigabit Virtual Function Network Driver machine # [ 0.973271] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 0.973411] sky2: driver version 1.30 machine # [ 0.975078] usbcore: registered new interface driver usb-storage machine # [ 0.975123] usbcore: registered new interface driver usbserial_generic machine # [ 0.975134] usbserial: USB Serial support registered for generic machine # [ 0.976133] ehci-pci 0000:00:07.0: EHCI Host Controller machine # [ 0.976155] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 machine # [ 0.976364] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 machine # [ 0.989195] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 machine # [ 0.989515] hub 1-0:1.0: USB hub found machine # [ 0.989566] hub 1-0:1.0: 6 ports detected machine # [ 0.990330] hv_vmbus: registering driver hyperv_keyboard machine # [ 0.991209] rtc-pl031 9010000.pl031: registered as rtc0 machine # [ 0.991235] rtc-pl031 9010000.pl031: setting system clock to 2026-09-27T03:13:15 UTC (1790478795) machine # [ 0.995915] i2c_dev: i2c /dev entries driver machine # [ 0.999182] sdhci: Secure Digital Host Controller Interface driver machine # [ 0.999188] sdhci: Copyright(c) Pierre Ossman machine # [ 1.001439] Synopsys Designware Multimedia Card Interface Driver machine # [ 1.001833] sdhci-pltfm: SDHCI platform and OF driver helper machine # [ 1.003394] hid: raw HID events driver (C) Jiri Kosina machine # [ 1.005852] usbcore: registered new interface driver usbhid machine # [ 1.005860] usbhid: USB HID core driver machine # [ 1.007019] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available machine # [ 1.011075] drop_monitor: Initializing network drop monitor service machine # [ 1.011211] NET: Registered PF_INET6 protocol family machine # [ 1.013518] Segment Routing with IPv6 machine # [ 1.013532] In-situ OAM (IOAM) with IPv6 machine # [ 1.013558] NET: Registered PF_PACKET protocol family machine # [ 1.013617] 9pnet: Installing 9P2000 support machine # [ 1.013659] Key type dns_resolver registered machine # [ 1.022483] registered taskstats version 1 machine # [ 1.022614] Loading compiled-in X.509 certificates machine # [ 1.031363] Demotion targets for Node 0: null machine # [ 1.032103] Key type .fscrypt registered machine # [ 1.032111] Key type fscrypt-provisioning registered machine # [ 1.032210] ima: No TPM chip found, activating TPM-bypass! machine # [ 1.032227] ima: Allocated hash algorithm: sha1 machine # [ 1.032247] ima: No architecture policies found machine # [ 1.032833] input: gpio-keys as /devices/platform/gpio-keys/input/input0 machine # [ 1.055013] clk: Disabling unused clocks machine # [ 1.055029] PM: genpd: Disabling unused power domains machine # [ 1.059251] Freeing unused kernel memory: 4736K machine # [ 1.059457] Run /init as init process machine # [ 1.073740] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.074272] systemd[1]: systemd 263-885fe07 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.074285] systemd[1]: Detected virtualization qemu. machine # [ 1.074325] systemd[1]: Detected architecture arm64. machine # [ 1.074329] systemd[1]: Running in initrd. machine # [ 1.074610] systemd[1]: Initializing machine ID from random generator. machine # [ 1.074701] systemd[1]: Hostname set to . machine # [ 1.172851] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.224931] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.233397] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.233562] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.233586] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.233604] systemd[1]: Reached target Path Units. machine # [ 1.233614] systemd[1]: Reached target Slice Units. machine # [ 1.233625] systemd[1]: Reached target Swaps. machine # [ 1.233636] systemd[1]: Reached target Timer Units. machine # [ 1.233755] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.233866] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.234083] systemd[1]: Listening on Journal Sockets. machine # [ 1.234155] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.234166] systemd[1]: Reached target Socket Units. machine # [ 1.235396] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.235451] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.239613] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 1.255629] systemd[1]: Mounting Kernel Configuration File System... machine # [ 1.263653] systemd[1]: Starting Journal Service... machine # [ 1.278400] systemd[1]: Starting Load Kernel Modules... machine # [ 1.278506] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.288698] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.311619] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.316844] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.317096] systemd[1]: Mounted Kernel Configuration File System. machine # [ 1.325872] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.338596] systemd-journald[71]: Collecting audit messages is disabled. machine # [ 1.343862] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.362395] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 machine # [ 1.362642] [drm] features: -virgl +edid -resource_blob -host_visible machine # [ 1.362646] [drm] features: -context_init machine # [ 1.363353] [drm] number of scanouts: 1 machine # [ 1.363364] [drm] number of cap sets: 0 machine # [ 1.380062] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.381727] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic machine # [ 1.381738] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 machine # [ 1.386680] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.402345] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 machine # [ 1.411828] Console: switching to colour frame buffer device 160x50 machine # [ 1.436073] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device machine # [ 1.448129] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.448398] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.448428] systemd[1]: Reached target Local File Systems. machine # [ 1.450145] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.455087] systemd[1]: Finished Load Kernel Modules. machine # [ 1.483716] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.487712] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0 machine # [ 1.519834] systemd[1]: Started Journal Service. machine # [ 1.509780] systemd-modules-load[72]: Inserted module 'dm_mod' machine # [ 1.511086] systemd-modules-load[72]: Module 'virtio_balloon' is built in machine # [ 1.518930] systemd-modules-load[72]: Module 'virtio_console' is built in machine # [ 1.524239] systemd-modules-load[72]: Inserted module 'virtio_gpu' machine # [ 1.528339] systemd-modules-load[72]: Module 'virtio_rng' is built in machine # [ 1.530553] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.536780] systemd[1]: Starting Create System Files and Directories... machine # [ 1.560391] systemd-udevd[79]: Using default interface naming scheme 'v261'. machine # [ 1.575985] systemd[1]: Finished Create System Files and Directories. machine # [ 1.590105] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 1.607562] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.664285] systemd[1]: Starting Virtual Console Setup... machine # [ 1.739098] systemd-vconsole-setup[102]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 1.742943] systemd[1]: Finished Virtual Console Setup. machine # [ 1.766745] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 machine # [ 1.766998] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0 machine # [ 2.406206] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.407244] systemd[1]: Reached target System Initialization. machine # [ 2.408141] systemd[1]: Reached target Basic System. machine # [ 2.547754] (udev-worker)[93]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.586842] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.643964] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 2.646411] systemd[1]: Reached target Initrd Root Device. machine # [ 2.650322] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 2.706368] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 2.714957] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 2.721408] systemd[1]: Mounting /sysroot... machine # [ 2.771365] EXT4-fs (vda): mounted filesystem de6f5adc-a7c2-4a41-976a-d69787b518d5 r/w with ordered data mode. Quota mode: none. machine # [ 2.761241] systemd[1]: Mounted /sysroot. machine # [ 2.764120] systemd[1]: Reached target Initrd Root File System. machine # [ 2.769397] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 2.797735] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 2.805499] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 2.808874] systemd[1]: Reloading... machine # [ 3.001550] systemd[1]: Reloading finished in 195 ms. machine # [ 3.031445] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace... machine # [ 3.037692] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace... machine # [ 3.043664] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 3.046811] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 3.048493] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 3.282128] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.300257] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 3.304935] systemd[1]: Mounting /sysroot/run... machine # [ 3.323950] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.348226] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.379643] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.428400] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.433805] systemd[1]: Mounted /sysroot/run. machine # [ 3.466906] fuse: init (API version 7.45) machine # [ 3.485443] virtiofs virtio6: discovered new tag: nix-store machine # [ 3.486370] virtiofs virtio6: virtio_fs_setup_dax: No cache capability machine # [ 3.476791] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.478316] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.501372] virtiofs virtio7: discovered new tag: shared machine # [ 3.502302] virtiofs virtio7: virtio_fs_setup_dax: No cache capability machine # [ 3.508707] virtiofs virtio8: discovered new tag: xchg machine # [ 3.509487] virtiofs virtio8: virtio_fs_setup_dax: No cache capability machine # [ 3.504078] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.511923] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.515677] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.963345] (udev-worker)[93]: mtd0ro: Failed to find and pin callout binary "/nix/store/h0gamn89nms8prhnc5v8m85jslcyklhg-systemd-263-885fe07/lib/udev/mtd_probe": No such file or directory machine # [ 3.972580] (udev-worker)[93]: mtd0ro: /etc/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.002968] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.004320] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.008761] systemd[1]: Stopping Virtual Console Setup... machine # [ 4.010430] systemd[1]: Starting Virtual Console Setup... machine # [ 4.024865] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.027647] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.029047] systemd[1]: Starting Virtual Console Setup... machine # [ 4.053126] systemd-vconsole-setup[156]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 4.056798] systemd[1]: Finished Virtual Console Setup. machine # [ 4.280808] systemd[1]: Mounting /sysroot/nix/store... machine # [ 4.324561] systemd[1]: Mounted /sysroot/nix/store. machine # [ 4.327714] systemd[1]: Reached target Initrd File Systems. machine # [ 4.329643] systemd[1]: Starting Find NixOS closure... machine # [ 4.340262] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 4.372737] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 4.379062] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. machine # [ 4.388274] systemd[1]: Finished Find NixOS closure. machine # [ 4.391560] systemd[1]: Reached target Initrd Default Target. machine # [ 4.396426] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 4.421862] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 4.423569] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 4.427388] systemd[1]: Stopped target Initrd Default Target. machine # [ 4.428784] systemd[1]: Stopped target Basic System. machine # [ 4.429580] systemd[1]: Stopped target Initrd Root Device. machine # [ 4.432363] systemd[1]: Stopped target Path Units. machine # [ 4.433235] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 4.435613] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 4.439485] systemd[1]: Stopped target Slice Units. machine # [ 4.445264] systemd[1]: Stopped target Socket Units. machine # [ 4.446121] systemd[1]: Stopped target System Initialization. machine # [ 4.447084] systemd[1]: Stopped target Swaps. machine # [ 4.447794] systemd[1]: Stopped target Timer Units. machine # [ 4.457545] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 4.459880] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 4.461301] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 4.462437] systemd[1]: Stopped Find NixOS closure. machine # [ 4.463345] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.469981] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 4.471337] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.480524] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 4.481759] systemd[1]: Stopped Load Kernel Modules. machine # [ 4.482566] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 4.483738] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 4.490167] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 4.492270] systemd[1]: Stopped Create System Files and Directories. machine # [ 4.496345] systemd[1]: Stopped target Local File Systems. machine # [ 4.497351] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 4.499248] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 4.500880] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 4.501756] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 4.502959] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.504164] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.504960] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 4.505951] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 4.507010] systemd[1]: systemd-udevd.service: Consumed 1.528s CPU time over 3.039s wall clock time, 22.4M memory peak. machine # [ 4.512399] systemd[1]: Starting Cleanup udev Database... machine # [ 4.513335] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 4.516827] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 4.520194] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 4.521458] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 4.524188] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 4.528135] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 4.529149] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 4.530196] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.557479] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 4.560533] systemd[1]: Finished Cleanup udev Database. machine # [ 4.562576] systemd[1]: Reached target Switch Root. machine # [ 4.563759] systemd[1]: Starting NixOS Activation... machine # [ 4.641400] initrd-nixos-activation-start[179]: booting system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test machine # [ 4.674108] initrd-nixos-activation-start[179]: running activation script... machine # [ 4.881345] initrd-nixos-activation-start[202]: setting up /etc... machine # [ 4.994293] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 4.997415] systemd[1]: Finished NixOS Activation. machine # [ 4.998749] systemd[1]: Starting Switch Root... machine # [ 5.020560] systemd[1]: Switching root. machine # [ 5.201310] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). machine # [ 5.352692] systemd[1]: systemd 263-885fe07 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) machine # [ 5.353699] systemd[1]: Detected virtualization qemu. machine # [ 5.354211] systemd[1]: Detected architecture arm64. machine # [ 5.354591] systemd[1]: Detected first boot. machine # [ 5.362505] systemd[1]: Initializing machine ID from random generator. machine # [ 5.489118] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 5.632377] systemd[1]: Applying preset policy. machine # [ 5.849750] systemd[1]: Populated /etc with preset unit settings. machine # [ 6.057410] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 6.058291] systemd[1]: Stopped initrd-switch-root.service. machine # [ 6.062797] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 6.066668] systemd[1]: Created slice Slice /system/getty. machine # [ 6.069240] systemd[1]: Created slice User and Session Slice. machine # [ 6.069832] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 6.070544] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 6.071134] systemd[1]: Expecting device /dev/hvc0... machine # [ 6.075845] systemd[1]: Expecting device /dev/ttyAMA0... machine # [ 6.076298] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 6.076970] systemd[1]: Stopped target initrd-fs.target. machine # [ 6.077594] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 6.078260] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 6.078901] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 6.085127] systemd[1]: Reached target Path Units. machine # [ 6.085577] systemd[1]: Reached target Remote File Systems. machine # [ 6.086343] systemd[1]: Reached target Slice Units. machine # [ 6.087026] systemd[1]: Reached target Swaps. machine # [ 6.092330] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 6.094304] systemd[1]: Listening on Process Core Dump Socket. machine # [ 6.097474] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 6.098922] systemd[1]: Listening on Factory Reset Management. machine # [ 6.099441] systemd[1]: Listening on Hostname Service Socket. machine # [ 6.105300] systemd[1]: Starting Journal Log Access Socket... machine # [ 6.106912] systemd[1]: Listening on Journal Audit Socket. machine # [ 6.111094] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 6.113533] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 6.114954] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.119363] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 6.124204] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 6.124798] systemd[1]: Listening on udev Varlink Socket. machine # [ 6.129623] systemd[1]: Mounting Huge Pages File System... machine # [ 6.136694] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 6.148191] systemd[1]: Mounting Kernel Debug File System... machine # [ 6.163645] systemd[1]: Mounting Kernel Trace File System... machine # [ 6.172807] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 6.176643] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 6.192500] systemd[1]: Mounting Kernel Configuration File System... machine # [ 6.193068] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 6.200248] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 6.205165] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 6.242696] systemd[1]: Mounting FUSE Control File System... machine # [ 6.243163] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 6.274443] systemd[1]: Starting Journal Service... machine # [ 6.294822] systemd[1]: Starting Load Kernel Modules... machine # [ 6.317831] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 6.345360] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 6.345788] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.365143] systemd[1]: Starting Coldplug All udev Devices... machine # [ 6.381406] systemd[1]: Listening on Journal Log Access Socket. machine # [ 6.382146] systemd[1]: Mounted Huge Pages File System. machine # [ 6.382598] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 6.382991] systemd[1]: Mounted Kernel Debug File System. machine # [ 6.383375] systemd[1]: Mounted Kernel Trace File System. machine # [ 6.403353] systemd-journald[273]: Collecting audit messages is enabled. machine # [ 6.411956] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 6.405077] systemd[1]: Queued start job for default target Multi-User System. machine # [ 6.408552] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 6.444020] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 6.444744] systemd[1]: Started Journal Service. machine # [ 6.438123] systemd[1]: Mounted Kernel Configuration File System. machine # [ 6.446985] systemd-modules-load[274]: Module 'atkbd' is built in machine # [ 6.451160] systemd-modules-load[274]: Module 'loop' is built in machine # [ 6.477604] systemd-oomd[275]: No swap; memory pressure usage will be degraded machine # [ 6.479302] systemd[1]: Finished Load Kernel Modules. machine # [ 6.505236] EXT4-fs (vda): re-mounted de6f5adc-a7c2-4a41-976a-d69787b518d5. machine # [ 6.496317] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 6.506310] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 6.521136] systemd[1]: Mounted FUSE Control File System. machine # [ 6.524721] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 6.538079] systemd[1]: Starting Firewall... machine # [ 6.540166] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 6.541245] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 6.558217] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 6.588388] systemd[1]: Starting Apply Kernel Variables... machine # [ 6.589438] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.658589] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 6.671765] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 6.696589] systemd-journald[273]: Received client request to flush runtime journal. machine # [ 6.782635] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 6.783722] systemd[1]: Reached target First Boot Complete. machine # [ 6.797874] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 6.817166] systemd[1]: Finished Apply Kernel Variables. machine # [ 6.865953] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 6.873234] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 6.875940] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 7.028996] systemd-udevd[307]: Using default interface naming scheme 'v261'. machine # [ 7.053290] systemd[1]: Mounting /run/wrappers... machine # [ 7.118797] systemd[1]: Mounted /run/wrappers. machine # [ 7.119748] systemd[1]: Reached target Local File Systems. machine # [ 7.128906] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 7.135392] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 7.136770] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 7.145516] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 7.153009] systemd[1]: Starting Create System Files and Directories... machine # [ 7.184583] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 7.301572] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 7.315666] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 7.381039] systemd[1]: Finished Create System Files and Directories. machine # [ 7.394611] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 7.401724] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 7.545363] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 7.555169] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 7.562248] systemd[1]: Starting Update is Completed... machine # [ 7.638751] systemd[1]: Finished Update is Completed. machine # [ 8.109659] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 8.116219] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 8.157188] systemd[1]: Finished Coldplug All udev Devices. machine # [ 8.158269] systemd[1]: Reached target System Initialization. machine # [ 8.159271] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 8.170123] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 8.171263] systemd[1]: Reached target Timer Units. machine # [ 8.173094] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 8.174129] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 8.175556] systemd[1]: Reached target Socket Units. machine # [ 8.184346] systemd[1]: Reached target Basic System. machine # [ 8.185174] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 8.186255] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 8.187259] systemd[1]: Starting Post-Boot Actions... machine # [ 8.202480] systemd[1]: Started Reset console on configuration changes. machine # [ 8.221923] systemd[1]: Starting resolvconf update... machine # [ 8.291645] systemd[1]: Starting D-Bus System Message Bus... machine # [ 8.329359] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 8.347359] nsncd[427]: Sep 27 03:13:22.864 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 8.360633] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 8.372623] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 8.373818] systemd[1]: Reached target User and Group Name Lookups. machine # [ 8.408394] systemd[1]: Starting User Login Management... machine # [ 8.421808] systemd[1]: Finished Post-Boot Actions. machine # [ 8.446330] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 8.502300] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 8.614696] dbus-broker-launch[434]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 8.625733] dbus-broker-launch[434]: NSS returned no entry for 'systemd-timesync' machine # [ 8.626983] dbus-broker-launch[434]: Invalid user-name in /nix/store/svs250hlinpmmh842v5vq65hzwi7rbkc-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 8.664768] systemd[1]: Started D-Bus System Message Bus. machine # [ 8.672936] systemd-logind[451]: New seat seat0. machine # [ 8.682575] systemd[1]: Started User Login Management. machine # [ 8.689073] systemd[1]: Starting linger-users.service... machine # [ 8.730884] dbus-broker-launch[434]: Ready machine # [ 8.772651] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 8.773822] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 8.774805] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 8.775742] systemd[1]: Stopping User and Group Name Lookups... machine # [ 8.796204] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 8.801226] systemd[1]: nscd.service: Deactivated successfully. machine # [ 8.802216] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 8.835903] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 8.883878] systemd[1]: Created slice Slice /user/1001. machine # [ 8.904364] systemd[1]: Starting User Runtime Directory /run/user/1001... machine # [ 8.947535] nsncd[520]: Sep 27 03:13:23.467 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 8.963778] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 8.977259] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 8.987038] systemd[1]: Finished linger-users.service. machine # [ 8.996581] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 9.013320] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 9.014503] systemd[1]: Reached target User and Group Name Lookups. machine # [ 9.023370] systemd[1]: Finished resolvconf update. machine # [ 9.033971] systemd[1]: Starting DHCP Client... machine # [ 9.059585] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. machine # [ 9.071568] systemd[1]: Started backdoor.service. machine # [ 9.116207] systemd[1]: Finished User Runtime Directory /run/user/1001. machine # [ 9.161300] systemd[1]: Starting User Manager for UID 1001... machine # [ 9.247099] (udev-worker)[329]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 9.293178] (udev-worker)[329]: Network interface NamePolicy= disabled on kernel command line. machine # [ 9.331554] (systemd)[563]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 9.380410] systemd-logind[451]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'. machine # [ 9.431894] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # connecting to host... machine: Guest shell says: b'Spawning backdoor root shell...\n' machine: connected to guest root shell machine: (connecting took 10.06 seconds) machine: (finished: waiting for the VM to finish booting, in 10.47 seconds) machine # [ 9.796812] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 9.836817] systemd-logind[451]: Watching system buttons on /dev/input/event0 (gpio-keys) machine # [ 9.878568] mousedev: PS/2 mouse device common for all mice machine # [ 9.886995] systemd[563]: Queued start job for default target Main User Target. machine # [ 9.911354] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 9.941470] systemd[563]: Created slice User Application Slice. machine # [ 9.942624] systemd[563]: Started Daily Cleanup of User's Temporary Directories. machine # [ 9.943798] systemd[563]: Reached target Paths. machine # [ 9.991577] systemd[563]: Reached target Timers. machine # [ 9.999850] systemd[563]: Starting D-Bus User Message Bus Socket... machine # [ 10.009082] systemd[563]: Starting Create User Files and Directories... machine # [ 10.010138] systemd[563]: Finished Create User Files and Directories. machine # [ 10.011208] systemd[563]: Listening on D-Bus User Message Bus Socket. machine # [ 10.031308] systemd[563]: Reached target Sockets. machine # [ 10.039363] systemd[563]: Reached target Basic System. machine # [ 10.042752] systemd[1]: Started User Manager for UID 1001. machine # [ 10.043651] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 10.052513] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 10.053546] systemd[563]: Reached target Main User Target. machine # [ 10.054364] systemd[563]: Startup finished in 498ms. machine # [ 10.055196] systemd-logind[451]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 10.095441] dhcpcd[581]: dhcpcd-10.3.2 starting machine # [ 10.101647] dhcpcd[606]: dev: loaded udev machine # [ 10.116442] systemd[1]: Finished Firewall. machine # [ 10.127953] systemd[1]: Reached target Preparation for Network. machine # [ 10.148018] 8021q: 802.1Q VLAN Support v1.8 machine # [ 10.137611] systemd[1]: Starting Address configuration of eth1... machine # [ 10.140916] systemd[1]: Starting Extra networking commands.... machine # [ 10.245227] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 10.282824] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 10.283369] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 10.287725] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 10.288067] cfg80211: failed to load regulatory.db machine # [ 10.473710] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 10.494938] dhcpcd[632]: /nix/store/1ki7bgdr0niilkg9jbkpcdaggbv0kb8a-openresolv-3.17.4/sbin/.resolvconf-wrapped: line 1250: kill: (622) - Operation not permitted machine # [ 10.516406] dhcpcd[632]: .resolvconf-wrapped: clearing stale lock pid 622 machine # [ 10.528474] network-addresses-eth1-start[609]: adding address 192.168.1.1/24... done machine # [ 10.595580] network-addresses-eth1-start[609]: adding address 2001:db8:1::1/64... done machine # [ 10.701698] systemd[1]: Finished Address configuration of eth1. machine # [ 10.736905] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 10.730283] dhcpcd[606]: eth0: waiting for carrier machine # [ 10.731135] dhcpcd[606]: eth0: carrier acquired machine # [ 10.805273] dhcpcd[606]: DUID 00:01:00:01:32:4b:46:55:52:54:00:12:34:56 machine # [ 10.806536] dhcpcd[606]: eth0: IAID 00:12:34:56 machine # [ 10.807262] dhcpcd[606]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 10.841653] dhcpcd[606]: eth0: soliciting a DHCP lease machine # [ 10.856820] dhcpcd[606]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 10.857898] dhcpcd[606]: eth0: probing address 10.0.2.15/24 machine # [ 11.248582] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 machine # [ 11.251035] systemd[1]: Finished Extra networking commands.. machine # [ 11.267354] systemd[1]: Reached target Network. machine # [ 11.280312] systemd[1]: Starting Permit User Sessions... machine # [ 11.522368] systemd[1]: Finished Permit User Sessions. machine # [ 11.536746] systemd[1]: Started Getty on tty1. machine # [ 11.537617] systemd[1]: Reached target Login Prompts. machine # [ 11.704899] systemd[1]: Starting Virtual Console Setup... machine # [ 11.705875] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 11.762873] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 11.768497] systemd[1]: Stopped Virtual Console Setup. machine # [ 11.769401] systemd[1]: Starting Virtual Console Setup... machine # [ 11.784435] systemd-logind[451]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 11.886945] systemd-vconsole-setup[714]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 11.894076] systemd[1]: Finished Virtual Console Setup. machine # [ 13.080533] dhcpcd[606]: eth0: soliciting an IPv6 router machine # [ 13.082615] dhcpcd[606]: eth0: Router Advertisement from fe80::2 machine # [ 13.084008] dhcpcd[606]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 13.085733] dhcpcd[606]: eth0: adding route to fec0::/64 machine # [ 13.086948] dhcpcd[606]: eth0: adding default route via fe80::2 machine # [ 16.041473] dhcpcd[606]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 16.041668] dhcpcd[606]: eth0: adding route to 10.0.2.0/24 machine # [ 16.041740] dhcpcd[606]: eth0: adding default route via 10.0.2.2 machine # [ 16.176158] systemd[1]: Started DHCP Client. machine # [ 16.178146] systemd[1]: Reached target Multi-User System. machine # [ 16.179041] systemd[1]: Startup finished in 1.048s (kernel) + 4.209s (initrd) + 10.919s (userspace) = 16.178s. machine: (finished: waiting for unit multi-user.target, in 17.89 seconds) machine: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test machine # Checking switch inhibitors... done machine # [ 17.357822] nixos[823]: switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test machine # [ 17.360637] systemd[1]: Stopped target Remote File Systems. machine # activating the configuration... machine # [ 17.363311] systemd[1]: Stopped target Local File Systems. machine # setting up /etc... machine # [ 17.627375] systemd[1]: Reload requested from client PID 823 ('.switch-to-conf') (unit backdoor.service)... machine # [ 17.629510] systemd[1]: Reloading... machine # [ 17.934121] systemd[1]: Reloading finished in 303 ms. machine # reloading user units for usertest... machine # [ 17.986611] systemd[563]: Created slice User Core Session Slice. machine # [ 17.989667] systemd[563]: Starting D-Bus User Message Bus... machine # [ 18.022507] dbus-broker-launch[893]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/svs250hlinpmmh842v5vq65hzwi7rbkc-system-path/share/dbus-1/services/org.freedesktop.import1.service' machine # [ 18.025752] dbus-broker-launch[893]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/svs250hlinpmmh842v5vq65hzwi7rbkc-system-path/share/dbus-1/services/org.freedesktop.machine1.service' machine # [ 18.028746] dbus-broker-launch[893]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/svs250hlinpmmh842v5vq65hzwi7rbkc-system-path/share/dbus-1/services/org.freedesktop.portable1.service' machine # [ 18.031709] dbus-broker-launch[893]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/svs250hlinpmmh842v5vq65hzwi7rbkc-system-path/share/dbus-1/services/org.freedesktop.systemd1.service' machine # [ 18.036371] dbus-broker-launch[893]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored machine # [ 18.038093] dbus-broker-launch[893]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored machine # [ 18.039820] systemd[563]: Started D-Bus User Message Bus. machine # [ 18.045464] dbus-broker-launch[893]: Ready machine # [ 18.083526] systemd[563]: Reexecution requested from client PID 892 ('switch-to-confi')... machine # [ 18.087262] systemd[563]: Reexecuting. machine # [ 18.147846] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 18.265887] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 18.272808] systemd[563]: Reload requested from client PID 892 ('switch-to-confi')... machine # [ 18.276093] systemd[563]: Reloading... machine # [ 18.384375] systemd[563]: Reloading finished in 108 ms. machine # [ 18.401634] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 18.410127] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 18.412520] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 18.414716] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 18.447539] systemd[563]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 18.459054] systemd[1]: Starting Re-setup tmpfiles on a system that is already running.... machine # [ 18.544158] systemd[1]: Finished Re-setup tmpfiles on a system that is already running.. machine # [ 18.547733] systemd[1]: Reached target Reactivate sysinit units. machine # [ 18.551888] systemd[1]: Reached target Remote File Systems. machine # [ 18.556716] systemd[1]: Starting linger-users.service... machine # [ 18.563368] systemd[1]: Reached target Local File Systems. machine # [ 18.570082] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 18.571818] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 18.583906] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 18.588368] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 18.590891] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 18.592750] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 18.594116] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 18.595392] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 18.602790] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 18.653896] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 18.656857] systemd[1]: Finished linger-users.service. machine # [ 18.786218] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 18.789968] systemd[1]: Finished Create SUID/SGID Wrappers. machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service machine # [ 19.046690] nixos[823]: finished switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test machine: (finished: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test, in 1.91 seconds) machine: must succeed: rm /etc/NIXOS machine: (finished: must succeed: rm /etc/NIXOS, in 0.03 seconds) machine: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/c014jg844hqw9kdghvk21k5i9sv26551-nixos-system-other-test/bin/switch-to-configuration test machine # this should succeed (config: /nix/store/c014jg844hqw9kdghvk21k5i9sv26551-nixos-system-other-test, action: test) machine # Checking switch inhibitors... done machine # [ 19.311796] nixos[970]: switching to system configuration /nix/store/c014jg844hqw9kdghvk21k5i9sv26551-nixos-system-other-test machine # [ 19.314611] systemd[1]: Stopped target Local File Systems. machine # activating the configuration... machine # [ 19.317479] systemd[1]: Stopped target Remote File Systems. machine # removing user ‘usertest’ machine # setting up /etc... machine # [ 19.625630] systemd[1]: Reload requested from client PID 970 ('.switch-to-conf') (unit backdoor.service)... machine # [ 19.627436] systemd[1]: Reloading... machine # [ 19.978441] systemd[1]: Reloading finished in 349 ms. machine # reloading user units for usertest... machine # [ 20.072242] systemd[563]: Reexecution requested from client PID 1041 ('switch-to-confi')... machine # [ 20.073789] systemd[563]: Reexecuting. machine # [ 20.127625] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 20.283260] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 20.290550] systemd[563]: Reload requested from client PID 1041 ('switch-to-confi')... machine # [ 20.293252] systemd[563]: Reloading... machine # [ 20.406683] systemd[563]: Reloading finished in 112 ms. machine # [ 20.425201] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 20.438071] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 20.440970] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 20.443737] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 20.479218] systemd[563]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 20.484433] systemd[1]: Stopped target Reactivate sysinit units. machine # reloading the following units: dbus-broker.service machine # [ 20.488842] systemd[1]: Stopping Reactivate sysinit units... machine # [ 20.489856] systemd[1]: Reached target Reactivate sysinit units. machine # [ 20.490786] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 20.494794] dbus-broker-launch[434]: Caught SIGHUP, trigger reload. machine # [ 20.499354] dbus-broker-launch[434]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 20.501758] dbus-broker-launch[434]: NSS returned no entry for 'systemd-timesync' machine # [ 20.502892] dbus-broker-launch[434]: Invalid user-name in /nix/store/4wwlicycgp0fjbv9vh5f4m0xw5da8ac9-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # restarting the following units: network-addresses-eth1.service machine # [ 20.520174] systemd[1]: Reloaded D-Bus System Message Bus. machine # [ 20.524964] systemd[1]: Stopping Address configuration of eth1... machine # [ 20.557870] network-addresses-eth1-pre-stop[1054]: deleting address 192.168.1.1/24... done machine # [ 20.565380] network-addresses-eth1-pre-stop[1054]: deleting address 2001:db8:1::1/64... done machine # [ 20.573248] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 20.575606] systemd[1]: Stopped Address configuration of eth1. machine # [ 20.581339] systemd[1]: Starting Address configuration of eth1... machine # [ 20.630176] network-addresses-eth1-start[1059]: adding address 192.168.1.2/24... done machine # [ 20.637015] network-addresses-eth1-start[1059]: adding address 2001:db8:1::2/64... done machine # [ 20.646516] systemd[1]: Finished Address configuration of eth1. machine # [ 20.651054] systemd[1]: Reached target Local File Systems. machine # [ 20.654824] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 20.659402] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 20.664533] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 20.665923] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 20.680144] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 20.681898] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 20.683257] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 20.684684] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 20.686094] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 20.687512] systemd[1]: Reached target Remote File Systems. machine # [ 20.867404] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 20.872689] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 20.874870] systemd[1]: Starting linger-users.service... machine # [ 20.907276] linger-users-start[1131]: id: ‘usertest’: no such user machine # [ 20.909545] linger-users-start[1129]: Removing linger for missing user usertest machine # [ 20.916432] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 20.919025] systemd[1]: Finished linger-users.service. machine # [ 21.174255] nixos[970]: finished switching to system configuration /nix/store/c014jg844hqw9kdghvk21k5i9sv26551-nixos-system-other-test machine: (finished: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/c014jg844hqw9kdghvk21k5i9sv26551-nixos-system-other-test/bin/switch-to-configuration test, in 2.10 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 21.393715] nixos[1137]: switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test machine # [ 21.396575] systemd[1]: Stopped target Local File Systems. machine # [ 21.398251] systemd[1]: Stopped target Remote File Systems. machine # [ 21.671312] systemd[1]: Reload requested from client PID 1137 ('.switch-to-conf') (unit backdoor.service)... machine # [ 21.673523] systemd[1]: Reloading... machine # [ 21.988890] systemd[1]: Reloading finished in 313 ms. machine # [ 22.077858] systemd[563]: Reexecution requested from client PID 1206 ('switch-to-confi')... machine # [ 22.082885] systemd[563]: Reexecuting. machine # [ 22.140835] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 22.255834] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 22.263254] systemd[563]: Reload requested from client PID 1206 ('switch-to-confi')... machine # [ 22.265878] systemd[563]: Reloading... machine # [ 22.374769] systemd[563]: Reloading finished in 107 ms. machine # [ 22.388312] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 22.399641] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 22.402565] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 22.404912] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 22.443633] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 22.448513] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 22.450047] systemd[1]: Stopping Reactivate sysinit units... machine # [ 22.451007] systemd[1]: Reached target Reactivate sysinit units. machine # [ 22.455859] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 22.457567] dbus-broker-launch[434]: Caught SIGHUP, trigger reload. machine # [ 22.463331] dbus-broker-launch[434]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 22.466011] dbus-broker-launch[434]: NSS returned no entry for 'systemd-timesync' machine # [ 22.467183] dbus-broker-launch[434]: Invalid user-name in /nix/store/svs250hlinpmmh842v5vq65hzwi7rbkc-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 22.480641] systemd[1]: Reloaded D-Bus System Message Bus. machine # [ 22.487875] systemd[1]: Stopping Address configuration of eth1... machine # [ 22.521449] network-addresses-eth1-pre-stop[1218]: deleting address 192.168.1.2/24... done machine # [ 22.530391] network-addresses-eth1-pre-stop[1218]: deleting address 2001:db8:1::2/64... done machine # [ 22.537961] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 22.543211] systemd[1]: Stopped Address configuration of eth1. machine # [ 22.545985] systemd[1]: Starting Address configuration of eth1... machine # [ 22.592596] network-addresses-eth1-start[1223]: adding address 192.168.1.1/24... done machine # [ 22.600315] network-addresses-eth1-start[1223]: adding address 2001:db8:1::1/64... done machine # [ 22.610200] systemd[1]: Finished Address configuration of eth1. machine # [ 22.614449] systemd[1]: Reached target Local File Systems. machine # [ 22.622104] systemd[1]: Reached target Remote File Systems. machine # [ 22.627270] systemd[1]: Starting linger-users.service... machine # [ 22.634044] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 22.641938] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 22.643107] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 22.653842] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 22.663313] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 22.668526] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.670073] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.671846] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.682492] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 22.709144] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 22.713753] systemd[1]: Finished linger-users.service. machine # [ 22.862409] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 22.865787] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 23.120263] nixos[1137]: finished switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.95 seconds) subtest: user services machine: waiting for unit user@1001.service machine: (finished: waiting for unit user@1001.service, in 0.07 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 23.384064] nixos[1296]: switching to system configuration /nix/store/a0krvhnw4d02dbzv739vbhn7wi243n8w-nixos-system-machine-test machine # [ 23.386903] systemd[1]: Stopped target Remote File Systems. machine # [ 23.389674] systemd[1]: Stopped target Local File Systems. machine # [ 23.718837] systemd[1]: Reload requested from client PID 1296 ('.switch-to-conf') (unit backdoor.service)... machine # [ 23.721020] systemd[1]: Reloading... machine # [ 24.241773] systemd[1]: Reloading finished in 518 ms. machine # [ 24.332233] systemd[563]: Reexecution requested from client PID 1366 ('switch-to-confi')... machine # [ 24.333733] systemd[563]: Reexecuting. machine # [ 24.387473] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 24.506664] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 24.513482] systemd[563]: Reload requested from client PID 1366 ('switch-to-confi')... machine # [ 24.517294] systemd[563]: Reloading... machine # [ 24.624340] systemd[563]: Reloading finished in 107 ms. machine # [ 24.641760] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 24.652313] systemd[563]: Starting usertest.service... machine # [ 24.683456] systemd[563]: Finished usertest.service. machine # [ 24.687910] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 24.689236] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 24.691630] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 24.726453] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 24.732859] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 24.733948] systemd[1]: Stopping Reactivate sysinit units... machine # [ 24.734876] systemd[1]: Reached target Reactivate sysinit units. machine # [ 24.746351] systemd[1]: Reached target Remote File Systems. machine # [ 24.749092] systemd[1]: Starting linger-users.service... machine # [ 24.756647] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.758182] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 24.759706] systemd[1]: Reached target Local File Systems. machine # [ 24.773016] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 24.774906] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 24.775779] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 24.783540] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 24.788543] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 24.790454] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.800622] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.855810] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 24.860129] systemd[1]: Finished linger-users.service. machine # [ 25.003840] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 25.007063] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 25.263107] nixos[1296]: finished switching to system configuration /nix/store/a0krvhnw4d02dbzv739vbhn7wi243n8w-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 25.315554] sudo[1436]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 25.331466] sudo[1436]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 25.345872] sudo[1436]: 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.08 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 25.536771] nixos[1445]: switching to system configuration /nix/store/a0krvhnw4d02dbzv739vbhn7wi243n8w-nixos-system-machine-test machine # [ 25.539310] systemd[1]: Stopped target Remote File Systems. machine # [ 25.541528] systemd[1]: Stopped target Local File Systems. machine # [ 25.815324] systemd[1]: Reload requested from client PID 1445 ('.switch-to-conf') (unit backdoor.service)... machine # [ 25.817515] systemd[1]: Reloading... machine # [ 26.135963] systemd[1]: Reloading finished in 316 ms. machine # [ 26.223575] systemd[563]: Reexecution requested from client PID 1514 ('switch-to-confi')... machine # [ 26.226233] systemd[563]: Reexecuting. machine # [ 26.283300] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 26.399594] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 26.406185] systemd[563]: Reload requested from client PID 1514 ('switch-to-confi')... machine # [ 26.410009] systemd[563]: Reloading... machine # [ 26.518031] systemd[563]: Reloading finished in 108 ms. machine # [ 26.532178] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 26.541875] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 26.544375] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 26.546515] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 26.579790] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 26.583819] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 26.586825] systemd[1]: Stopping Reactivate sysinit units... machine # [ 26.587704] systemd[1]: Reached target Reactivate sysinit units. machine # [ 26.589664] systemd[1]: Reached target Local File Systems. machine # [ 26.593952] systemd[1]: Reached target Remote File Systems. machine # [ 26.598375] systemd[1]: Starting linger-users.service... machine # [ 26.608525] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.610007] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 26.614623] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 26.624346] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 26.625309] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 26.626897] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 26.639821] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 26.644576] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.645990] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.684930] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 26.688718] systemd[1]: Finished linger-users.service. machine # [ 26.825010] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 26.828675] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 27.082837] nixos[1445]: finished switching to system configuration /nix/store/a0krvhnw4d02dbzv739vbhn7wi243n8w-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.74 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1 machine # [ 27.276096] nixos[1586]: switching to system configuration /nix/store/00l6rg5g44qsr24kglq18z3yvxn3iyf0-nixos-system-machine-test machine # [ 27.278677] systemd[1]: Stopped target Remote File Systems. machine # [ 27.281271] systemd[1]: Stopped target Local File Systems. machine # [ 27.562092] systemd[1]: Reload requested from client PID 1586 ('.switch-to-conf') (unit backdoor.service)... machine # [ 27.564375] systemd[1]: Reloading... machine # [ 27.879474] systemd[1]: Reloading finished in 313 ms. machine # [ 27.978000] systemd[563]: Stopped usertest.service. machine # [ 27.982031] systemd[563]: Reexecution requested from client PID 1655 ('switch-to-confi')... machine # [ 27.983557] systemd[563]: Reexecuting. machine # [ 28.039511] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 28.153713] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 28.161416] systemd[563]: Reload requested from client PID 1655 ('switch-to-confi')... machine # [ 28.162868] systemd[563]: Reloading... machine # [ 28.271006] systemd[563]: Reloading finished in 106 ms. machine # [ 28.289757] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 28.298880] systemd[563]: Starting usertest.service... machine # [ 28.325359] systemd[563]: Finished usertest.service. machine # [ 28.329476] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 28.330678] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 28.332782] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 28.363952] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 28.368163] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 28.370602] systemd[1]: Stopping Reactivate sysinit units... machine # [ 28.371548] systemd[1]: Reached target Reactivate sysinit units. machine # [ 28.373517] systemd[1]: Reached target Remote File Systems. machine # [ 28.378017] systemd[1]: Reached target Local File Systems. machine # [ 28.382529] systemd[1]: Starting linger-users.service... machine # [ 28.392763] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 28.401663] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 28.402652] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 28.403971] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 28.415158] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 28.417054] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 28.418420] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 28.419669] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 28.427036] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 28.475494] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 28.480169] systemd[1]: Finished linger-users.service. machine # [ 28.610614] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 28.613640] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 28.869480] nixos[1586]: finished switching to system configuration /nix/store/00l6rg5g44qsr24kglq18z3yvxn3iyf0-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 1.79 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 28.912826] sudo[1725]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 28.927534] sudo[1725]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 28.941459] sudo[1725]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 29.125610] nixos[1734]: switching to system configuration /nix/store/fhjk6yz8lwlgf0dzagiwcckgvg3q6mpv-nixos-system-machine-test machine # [ 29.128853] systemd[1]: Stopped target Remote File Systems. machine # [ 29.130856] systemd[1]: Stopped target Local File Systems. machine # [ 29.409394] systemd[1]: Reload requested from client PID 1734 ('.switch-to-conf') (unit backdoor.service)... machine # [ 29.411398] systemd[1]: Reloading... machine # [ 29.719442] systemd[1]: Reloading finished in 306 ms. machine # [ 29.812428] systemd[563]: Reexecution requested from client PID 1803 ('switch-to-confi')... machine # [ 29.813881] systemd[563]: Reexecuting. machine # [ 29.871248] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.988386] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.994923] systemd[563]: Reload requested from client PID 1803 ('switch-to-confi')... machine # [ 29.998658] systemd[563]: Reloading... machine # [ 30.104396] systemd[563]: Reloading finished in 106 ms. machine # [ 30.120186] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 30.126549] systemd[563]: Stopped usertest.service. machine # [ 30.128616] systemd[563]: Stopping usertest.service... machine # [ 30.130864] systemd[563]: Starting usertest.service... machine # [ 30.157275] systemd[563]: Finished usertest.service. machine # [ 30.162375] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 30.164777] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 30.166994] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 30.214958] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 30.223486] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 30.230225] systemd[1]: Stopping Reactivate sysinit units... machine # [ 30.231302] systemd[1]: Reached target Reactivate sysinit units. machine # [ 30.234106] systemd[1]: Reached target Local File Systems. machine # [ 30.238093] systemd[1]: Reached target Remote File Systems. machine # [ 30.240875] systemd[1]: Starting linger-users.service... machine # [ 30.253956] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 30.255540] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 30.267059] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 30.276546] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 30.277486] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 30.278818] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 30.289927] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 30.291735] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 30.302883] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 30.361670] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 30.364179] systemd[1]: Finished linger-users.service. machine # [ 30.490165] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 30.493872] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 30.749876] nixos[1734]: finished switching to system configuration /nix/store/fhjk6yz8lwlgf0dzagiwcckgvg3q6mpv-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1, in 1.81 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 30.791591] sudo[1874]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 30.806732] sudo[1874]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 30.821770] sudo[1874]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1 machine # [ 31.008066] nixos[1883]: switching to system configuration /nix/store/avwfqxk3gm5hs6vhc6ydmvsk55sy1zfh-nixos-system-machine-test machine # [ 31.011822] systemd[1]: Stopped target Local File Systems. machine # [ 31.013578] systemd[1]: Stopped target Remote File Systems. machine # [ 31.295559] systemd[1]: Reload requested from client PID 1883 ('.switch-to-conf') (unit backdoor.service)... machine # [ 31.298821] systemd[1]: Reloading... machine # [ 31.636851] systemd[1]: Reloading finished in 336 ms. machine # [ 31.732453] systemd[563]: Reexecution requested from client PID 1952 ('switch-to-confi')... machine # [ 31.736125] systemd[563]: Reexecuting. machine # [ 31.791489] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 31.907654] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 31.914621] systemd[563]: Reload requested from client PID 1952 ('switch-to-confi')... machine # [ 31.917303] systemd[563]: Reloading... machine # [ 32.024970] systemd[563]: Reloading finished in 106 ms. machine # [ 32.040218] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.049219] systemd[563]: Reloading usertest.service... machine # [ 32.073800] systemd[563]: Reloaded usertest.service. machine # [ 32.078958] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 32.081460] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 32.083590] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 32.116974] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 32.120690] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 32.123762] systemd[1]: Stopping Reactivate sysinit units... machine # [ 32.126425] systemd[1]: Reached target Reactivate sysinit units. machine # [ 32.127369] systemd[1]: Reached target Local File Systems. machine # [ 32.131790] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 32.140517] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 32.141478] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 32.142866] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 32.155762] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 32.157732] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 32.159175] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 32.161204] systemd[1]: Reached target Remote File Systems. machine # [ 32.162108] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 32.163880] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 32.327412] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 32.332770] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 32.334741] systemd[1]: Starting linger-users.service... machine # [ 32.379127] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 32.381789] systemd[1]: Finished linger-users.service. machine # [ 32.637797] nixos[1883]: finished switching to system configuration /nix/store/avwfqxk3gm5hs6vhc6ydmvsk55sy1zfh-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1, in 1.82 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 32.681563] sudo[2022]: 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 # [ 32.696675] sudo[2022]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 32.710564] sudo[2022]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 32.900778] nixos[2031]: switching to system configuration /nix/store/a0krvhnw4d02dbzv739vbhn7wi243n8w-nixos-system-machine-test machine # [ 32.903481] systemd[1]: Stopped target Local File Systems. machine # [ 32.905645] systemd[1]: Stopped target Remote File Systems. machine # [ 33.170781] systemd[1]: Reload requested from client PID 2031 ('.switch-to-conf') (unit backdoor.service)... machine # [ 33.173099] systemd[1]: Reloading... machine # [ 33.486617] systemd[1]: Reloading finished in 312 ms. machine # [ 33.625955] systemd[563]: Stopped usertest.service. machine # [ 33.629758] systemd[563]: Reexecution requested from client PID 2100 ('switch-to-confi')... machine # [ 33.631288] systemd[563]: Reexecuting. machine # [ 33.691829] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.811624] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.819043] systemd[563]: Reload requested from client PID 2100 ('switch-to-confi')... machine # [ 33.821778] systemd[563]: Reloading... machine # [ 33.932184] systemd[563]: Reloading finished in 109 ms. machine # [ 33.948198] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.959440] systemd[563]: Starting usertest.service... machine # [ 33.991019] systemd[563]: Finished usertest.service. machine # [ 33.994113] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 33.996720] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 33.998893] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 34.034429] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 34.038414] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 34.040128] systemd[1]: Stopping Reactivate sysinit units... machine # [ 34.041026] systemd[1]: Reached target Reactivate sysinit units. machine # [ 34.047064] systemd[1]: Reached target Local File Systems. machine # [ 34.049911] systemd[1]: Reached target Remote File Systems. machine # [ 34.055227] systemd[1]: Starting linger-users.service... machine # [ 34.060266] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 34.064401] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 34.065332] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 34.066678] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 34.082436] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 34.084261] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.085597] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.086873] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 34.089413] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 34.143132] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 34.148580] systemd[1]: Finished linger-users.service. machine # [ 34.272826] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 34.276729] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 34.530605] nixos[2031]: finished switching to system configuration /nix/store/a0krvhnw4d02dbzv739vbhn7wi243n8w-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.82 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 34.573082] sudo[2171]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 34.587988] sudo[2171]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 34.601887] sudo[2171]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1 machine # [ 34.786825] nixos[2180]: switching to system configuration /nix/store/9l0csyzxsd2y9xkfd8i2d8kwf492fz20-nixos-system-machine-test machine # [ 34.789638] systemd[1]: Stopped target Local File Systems. machine # [ 34.792196] systemd[1]: Stopped target Remote File Systems. machine # [ 35.070161] systemd[1]: Reload requested from client PID 2180 ('.switch-to-conf') (unit backdoor.service)... machine # [ 35.072406] systemd[1]: Reloading... machine # [ 35.376278] systemd[1]: Reloading finished in 302 ms. machine # [ 35.464483] systemd[563]: Reexecution requested from client PID 2249 ('switch-to-confi')... machine # [ 35.468470] systemd[563]: Reexecuting. machine # [ 35.510912] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.659362] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.668081] systemd[563]: Reload requested from client PID 2249 ('switch-to-confi')... machine # [ 35.672052] systemd[563]: Reloading... machine # [ 35.777121] systemd[563]: Reloading finished in 105 ms. machine # [ 35.792180] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.801380] systemd[563]: Reloading usertest.service... machine # [ 35.825118] systemd[563]: Reloaded usertest.service. machine # [ 35.830134] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 35.832466] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 35.834508] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 35.866954] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 35.871412] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 35.876675] systemd[1]: Stopping Reactivate sysinit units... machine # [ 35.877626] systemd[1]: Reached target Reactivate sysinit units. machine # [ 35.878571] systemd[1]: Reached target Remote File Systems. machine # [ 35.880929] systemd[1]: Starting linger-users.service... machine # [ 35.892916] systemd[1]: Reached target Local File Systems. machine # [ 35.893831] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 35.905435] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 35.906442] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 35.907753] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 35.921024] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 35.922860] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 35.933194] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 35.936420] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 35.937854] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 35.977046] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 35.980387] systemd[1]: Finished linger-users.service. machine # [ 36.106934] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 36.108843] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 36.365302] nixos[2180]: finished switching to system configuration /nix/store/9l0csyzxsd2y9xkfd8i2d8kwf492fz20-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 1.76 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 36.407391] sudo[2319]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 36.421718] sudo[2319]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 36.434789] sudo[2319]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds) machine: must fail: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1 machine # [ 36.621877] nixos[2328]: switching to system configuration /nix/store/h1p77s6xwylm76s6vyq69hxqy2j1a4pl-nixos-system-machine-test machine # [ 36.625205] systemd[1]: Stopped target Local File Systems. machine # [ 36.627172] systemd[1]: Stopped target Remote File Systems. machine # [ 36.965742] systemd[1]: Reload requested from client PID 2328 ('.switch-to-conf') (unit backdoor.service)... machine # [ 36.967479] systemd[1]: Reloading... machine # [ 37.285631] systemd[1]: Reloading finished in 315 ms. machine # [ 37.373809] systemd[563]: Stopped usertest.service. machine # [ 37.377686] systemd[563]: Reexecution requested from client PID 2398 ('switch-to-confi')... machine # [ 37.379224] systemd[563]: Reexecuting. machine # [ 37.439453] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.559677] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.567461] systemd[563]: Reload requested from client PID 2398 ('switch-to-confi')... machine # [ 37.570892] systemd[563]: Reloading... machine # [ 37.678620] systemd[563]: Reloading finished in 109 ms. machine # [ 37.696181] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.706891] systemd[563]: Starting usertest.service... machine # [ 37.733350] systemd[563]: usertest.service: Main process exited, code=exited, status=1/FAILURE machine # [ 37.736405] systemd[563]: usertest.service: Failed with result 'exit-code'. machine # [ 37.737843] systemd[563]: Failed to start usertest.service. machine # [ 37.743011] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 37.744630] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 37.745640] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 37.779984] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 37.784107] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 37.786599] systemd[1]: Stopping Reactivate sysinit units... machine # [ 37.787534] systemd[1]: Reached target Reactivate sysinit units. machine # [ 37.792000] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.793768] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 37.797051] systemd[1]: Reached target Remote File Systems. machine # [ 37.799733] systemd[1]: Reached target Local File Systems. machine # [ 37.805793] systemd[1]: Starting linger-users.service... machine # [ 37.816594] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 37.824529] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 37.825592] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 37.827054] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 37.838537] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 37.844456] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.852473] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.892780] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 37.896239] systemd[1]: Finished linger-users.service. machine # [ 38.031521] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 38.034602] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 38.288664] nixos[2328]: switching to system configuration /nix/store/h1p77s6xwylm76s6vyq69hxqy2j1a4pl-nixos-system-machine-test failed (status 4) machine: (finished: must fail: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 1.86 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 38.497535] nixos[2471]: switching to system configuration /nix/store/a0krvhnw4d02dbzv739vbhn7wi243n8w-nixos-system-machine-test machine # [ 38.500059] systemd[1]: Stopped target Remote File Systems. machine # [ 38.502686] systemd[1]: Stopped target Local File Systems. machine # [ 38.771699] systemd[1]: Reload requested from client PID 2471 ('.switch-to-conf') (unit backdoor.service)... machine # [ 38.774022] systemd[1]: Reloading... machine # [ 39.088263] systemd[1]: Reloading finished in 312 ms. machine # [ 39.173309] systemd[563]: Reexecution requested from client PID 2540 ('switch-to-confi')... machine # [ 39.177718] systemd[563]: Reexecuting. machine # [ 39.231357] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.342831] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.349846] systemd[563]: Reload requested from client PID 2540 ('switch-to-confi')... machine # [ 39.354000] systemd[563]: Reloading... machine # [ 39.458354] systemd[563]: Reloading finished in 105 ms. machine # [ 39.473630] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.482688] systemd[563]: Starting usertest.service... machine # [ 39.508970] systemd[563]: Finished usertest.service. machine # [ 39.512255] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 39.514497] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 39.516639] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 39.547588] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 39.551809] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 39.555980] systemd[1]: Stopping Reactivate sysinit units... machine # [ 39.557875] systemd[1]: Reached target Reactivate sysinit units. machine # [ 39.558911] systemd[1]: Reached target Remote File Systems. machine # [ 39.564204] systemd[1]: Reached target Local File Systems. machine # [ 39.565162] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 39.576856] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 39.577937] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 39.579305] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 39.588191] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 39.589916] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.591261] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.592621] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.593969] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 39.746534] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 39.751664] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 39.754243] systemd[1]: Starting linger-users.service... machine # [ 39.797374] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 39.800652] systemd[1]: Finished linger-users.service. machine # [ 40.054769] nixos[2471]: finished switching to system configuration /nix/store/a0krvhnw4d02dbzv739vbhn7wi243n8w-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.77 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 40.098005] sudo[2610]: 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.113669] sudo[2610]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 40.126550] sudo[2610]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 40.369748] nixos[2619]: switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test machine # [ 40.372844] systemd[1]: Stopped target Remote File Systems. machine # [ 40.375643] systemd[1]: Stopped target Local File Systems. machine # [ 40.643562] systemd[1]: Reload requested from client PID 2619 ('.switch-to-conf') (unit backdoor.service)... machine # [ 40.646048] systemd[1]: Reloading... machine # [ 40.955088] systemd[1]: Reloading finished in 307 ms. machine # [ 41.040697] systemd[563]: Stopped usertest.service. machine # [ 41.044409] systemd[563]: Reexecution requested from client PID 2689 ('switch-to-confi')... machine # [ 41.045831] systemd[563]: Reexecuting. machine # [ 41.103481] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 41.218853] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 41.225750] systemd[563]: Reload requested from client PID 2689 ('switch-to-confi')... machine # [ 41.228334] systemd[563]: Reloading... machine # [ 41.335179] systemd[563]: Reloading finished in 105 ms. machine # [ 41.352198] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 41.362803] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 41.363882] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 41.368203] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 41.395023] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 41.399607] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 41.402184] systemd[1]: Stopping Reactivate sysinit units... machine # [ 41.403213] systemd[1]: Reached target Reactivate sysinit units. machine # [ 41.408647] systemd[1]: Reached target Remote File Systems. machine # [ 41.411351] systemd[1]: Starting linger-users.service... machine # [ 41.414532] systemd[1]: Reached target Local File Systems. machine # [ 41.424798] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 41.426271] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 41.431562] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 41.438813] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 41.439762] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 41.445920] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 41.452325] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 41.456900] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 41.458357] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 41.501138] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 41.504145] systemd[1]: Finished linger-users.service. machine # [ 41.633437] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 41.636917] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 41.890965] nixos[2619]: finished switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.77 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 41.933384] sudo[2757]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 41.947879] sudo[2757]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 41.963153] sudo[2757]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 41.996826] sudo[2763]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 42.007945] sudo[2763]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 42.017086] sudo[2763]: 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.05 seconds) machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 42.044571] sudo[2769]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 42.054333] sudo[2769]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 42.062902] sudo[2769]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 42.089427] sudo[2775]: 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 # [ 42.099073] sudo[2775]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 42.107050] sudo[2775]: 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.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 42.132725] sudo[2781]: 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 # [ 42.141990] sudo[2781]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 42.150129] systemd[563]: Reload requested from client PID 2786 ('systemctl')... machine # [ 42.154054] systemd[563]: Reloading... machine # [ 42.261880] systemd[563]: Reloading finished in 109 ms. machine # [ 42.280325] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.290670] sudo[2781]: 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.18 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 42.318485] sudo[2791]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service machine # [ 42.328852] sudo[2791]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 42.341865] systemd[563]: Starting migrated.service... machine # [ 42.366072] systemd[563]: Finished migrated.service. machine # [ 42.370303] sudo[2791]: 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.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 42.396975] sudo[2799]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 42.406892] sudo[2799]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 42.417076] sudo[2799]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 42.443142] sudo[2805]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 42.452706] sudo[2805]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 42.460318] sudo[2805]: 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.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 42.486541] sudo[2811]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 42.496921] sudo[2811]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 42.512612] sudo[2811]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1 machine # [ 42.687688] nixos[2820]: switching to system configuration /nix/store/fan85i4dd0va1lwihgr2s8ra0iqs0rwq-nixos-system-machine-test machine # [ 42.690890] systemd[1]: Stopped target Remote File Systems. machine # [ 42.692733] systemd[1]: Stopped target Local File Systems. machine # [ 42.961857] systemd[1]: Reload requested from client PID 2820 ('.switch-to-conf') (unit backdoor.service)... machine # [ 42.963911] systemd[1]: Reloading... machine # [ 43.267404] systemd[1]: Reloading finished in 301 ms. machine # [ 43.355509] systemd[563]: Reexecution requested from client PID 2889 ('switch-to-confi')... machine # [ 43.359375] systemd[563]: Reexecuting. machine # [ 43.407283] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.569930] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.580427] systemd[563]: Reload requested from client PID 2889 ('switch-to-confi')... machine # [ 43.581885] systemd[563]: Reloading... machine # [ 43.698696] systemd[563]: Reloading finished in 115 ms. machine # [ 43.716406] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.727061] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 43.729667] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 43.732118] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 43.788812] systemd[563]: Reload requested from client PID 2902 ('systemctl') (unit nixos-activation.service)... machine # [ 43.790659] systemd[563]: Reloading... machine # [ 43.906502] systemd[563]: Reloading finished in 114 ms. machine # [ 43.924196] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.946303] systemd[563]: Stopped migrated.service. machine # [ 43.957774] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 43.960889] systemd[563]: Reload requested from client PID 2889 ('switch-to-confi')... machine # [ 43.965100] systemd[563]: Reloading... machine # [ 44.074517] systemd[563]: Reloading finished in 110 ms. machine # [ 44.092403] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 44.103670] systemd[563]: Starting migrated.service... machine # [ 44.135668] systemd[563]: Finished migrated.service. machine # [ 44.140641] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 44.141676] systemd[1]: Stopping Reactivate sysinit units... machine # [ 44.142625] systemd[1]: Reached target Reactivate sysinit units. machine # [ 44.147900] systemd[1]: Reached target Local File Systems. machine # [ 44.152262] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 44.159543] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 44.163562] systemd[1]: Reached target Remote File Systems. machine # [ 44.168820] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 44.170495] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 44.171334] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 44.178289] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 44.180883] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 44.182652] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 44.183925] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 44.343027] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 44.348461] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 44.350622] systemd[1]: Starting linger-users.service... machine # [ 44.393459] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 44.396823] systemd[1]: Finished linger-users.service. machine # [ 44.651255] nixos[2820]: finished switching to system configuration /nix/store/fan85i4dd0va1lwihgr2s8ra0iqs0rwq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 2.14 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 44.699635] sudo[2972]: 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 # [ 44.714934] sudo[2972]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 44.729103] sudo[2972]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 44.763256] sudo[2978]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 44.775162] sudo[2978]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 44.794420] sudo[2978]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 44.824605] sudo[2984]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 44.834347] sudo[2984]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 44.843149] sudo[2984]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 45.019352] nixos[2993]: switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test machine # [ 45.023254] systemd[1]: Stopped target Local File Systems. machine # [ 45.024870] systemd[1]: Stopped target Remote File Systems. machine # [ 45.290788] systemd[1]: Reload requested from client PID 2993 ('.switch-to-conf') (unit backdoor.service)... machine # [ 45.292950] systemd[1]: Reloading... machine # [ 45.598679] systemd[1]: Reloading finished in 304 ms. machine # [ 45.684660] systemd[563]: Stopped migrated.service. machine # [ 45.688298] systemd[563]: Reexecution requested from client PID 3062 ('switch-to-confi')... machine # [ 45.689748] systemd[563]: Reexecuting. machine # [ 45.739175] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.851739] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.858758] systemd[563]: Reload requested from client PID 3062 ('switch-to-confi')... machine # [ 45.862704] systemd[563]: Reloading... machine # [ 45.970690] systemd[563]: Reloading finished in 109 ms. machine # [ 45.984183] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.995108] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 45.997511] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 45.999694] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 46.032906] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 46.037022] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 46.040152] systemd[1]: Stopping Reactivate sysinit units... machine # [ 46.041140] systemd[1]: Reached target Reactivate sysinit units. machine # [ 46.045962] systemd[1]: Reached target Local File Systems. machine # [ 46.048708] systemd[1]: Reached target Remote File Systems. machine # [ 46.053463] systemd[1]: Starting linger-users.service... machine # [ 46.062610] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.071412] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 46.076649] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 46.078392] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 46.079207] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 46.087743] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 46.095461] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 46.101948] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.103591] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.143959] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 46.148177] systemd[1]: Finished linger-users.service. machine # [ 46.277618] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 46.280957] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 46.534935] nixos[2993]: finished switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.70 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 46.579200] sudo[3130]: 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 # [ 46.594268] sudo[3130]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 46.609650] sudo[3130]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 46.642060] sudo[3136]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 46.653853] sudo[3136]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 46.662799] sudo[3136]: 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.05 seconds) machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 46.691082] sudo[3142]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 46.701460] sudo[3142]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 46.709543] sudo[3142]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 46.736436] sudo[3148]: 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 # [ 46.746857] sudo[3148]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 46.754251] sudo[3148]: 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.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 46.780743] sudo[3154]: 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 # [ 46.790345] sudo[3154]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 46.798518] systemd[563]: Reload requested from client PID 3159 ('systemctl')... machine # [ 46.802516] systemd[563]: Reloading... machine # [ 46.908592] systemd[563]: Reloading finished in 107 ms. machine # [ 46.920206] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.933392] sudo[3154]: 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.18 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 46.962357] sudo[3164]: 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 # [ 46.971796] sudo[3164]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 46.985480] systemd[563]: Starting migrated.service... machine # [ 47.006573] systemd[563]: Finished migrated.service. machine # [ 47.010667] sudo[3164]: 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.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 47.038398] sudo[3172]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 47.048818] sudo[3172]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.058654] sudo[3172]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 47.085826] sudo[3178]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 47.095485] sudo[3178]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.103806] sudo[3178]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 47.130409] sudo[3184]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 47.140788] sudo[3184]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.156498] sudo[3184]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1 machine # [ 47.331606] nixos[3193]: switching to system configuration /nix/store/mnzd39lra6879jpig7hjiqnxkl70bxcj-nixos-system-machine-test machine # [ 47.337214] systemd[1]: Stopped target Remote File Systems. machine # [ 47.338148] systemd[1]: Stopped target Local File Systems. machine # [ 47.607678] systemd[1]: Reload requested from client PID 3193 ('.switch-to-conf') (unit backdoor.service)... machine # [ 47.609914] systemd[1]: Reloading... machine # [ 47.912089] systemd[1]: Reloading finished in 300 ms. machine # [ 48.003552] systemd[563]: Reexecution requested from client PID 3262 ('switch-to-confi')... machine # [ 48.007389] systemd[563]: Reexecuting. machine # [ 48.055007] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.165839] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.172544] systemd[563]: Reload requested from client PID 3262 ('switch-to-confi')... machine # [ 48.176081] systemd[563]: Reloading... machine # [ 48.280992] systemd[563]: Reloading finished in 105 ms. machine # [ 48.292167] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.301489] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 48.303916] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 48.306180] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 48.344614] systemd[563]: Reload requested from client PID 3274 ('systemctl') (unit nixos-activation.service)... machine # [ 48.347667] systemd[563]: Reloading... machine # [ 48.458299] systemd[563]: Reloading finished in 109 ms. machine # [ 48.472172] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.489315] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 48.492147] systemd[563]: Reload requested from client PID 3262 ('switch-to-confi')... machine # [ 48.495994] systemd[563]: Reloading... machine # [ 48.602366] systemd[563]: Reloading finished in 107 ms. machine # [ 48.616174] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.625554] systemd[563]: Stopped migrated.service. machine # [ 48.626429] systemd[563]: Stopping migrated.service... machine # [ 48.628262] systemd[563]: Starting migrated.service... machine # [ 48.654369] systemd[563]: Finished migrated.service. machine # [ 48.660823] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 48.661805] systemd[1]: Stopping Reactivate sysinit units... machine # [ 48.662742] systemd[1]: Reached target Reactivate sysinit units. machine # [ 48.667244] systemd[1]: Reached target Remote File Systems. machine # [ 48.671736] systemd[1]: Starting linger-users.service... machine # [ 48.680405] systemd[1]: Reached target Local File Systems. machine # [ 48.682776] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.687455] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 48.695674] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 48.704618] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 48.705523] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 48.706793] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 48.715834] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 48.717917] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.719598] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.755558] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 48.758727] systemd[1]: Finished linger-users.service. machine # [ 48.890562] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 48.893607] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 49.149330] nixos[3193]: finished switching to system configuration /nix/store/mnzd39lra6879jpig7hjiqnxkl70bxcj-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 2.00 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 49.192888] sudo[3343]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 49.207547] sudo[3343]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 49.222357] sudo[3343]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 49.256280] sudo[3349]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 49.267357] sudo[3349]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 49.285673] sudo[3349]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 49.312728] sudo[3355]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 49.321873] sudo[3355]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 49.328973] sudo[3355]: 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.04 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 49.500189] nixos[3364]: switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test machine # [ 49.503971] systemd[1]: Stopped target Remote File Systems. machine # [ 49.506037] systemd[1]: Stopped target Local File Systems. machine # [ 49.759470] systemd[1]: Reload requested from client PID 3364 ('.switch-to-conf') (unit backdoor.service)... machine # [ 49.761590] systemd[1]: Reloading... machine # [ 50.057237] systemd[1]: Reloading finished in 294 ms. machine # [ 50.139756] systemd[563]: Stopped migrated.service. machine # [ 50.143143] systemd[563]: Reexecution requested from client PID 3433 ('switch-to-confi')... machine # [ 50.145919] systemd[563]: Reexecuting. machine # [ 50.208156] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 50.322263] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 50.329284] systemd[563]: Reload requested from client PID 3433 ('switch-to-confi')... machine # [ 50.330666] systemd[563]: Reloading... machine # [ 50.437912] systemd[563]: Reloading finished in 105 ms. machine # [ 50.452172] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 50.461494] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 50.463853] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 50.466225] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 50.499252] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 50.503089] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 50.505802] systemd[1]: Stopping Reactivate sysinit units... machine # [ 50.506800] systemd[1]: Reached target Reactivate sysinit units. machine # [ 50.509193] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 50.510635] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 50.514202] systemd[1]: Reached target Local File Systems. machine # [ 50.520726] systemd[1]: Reached target Remote File Systems. machine # [ 50.523883] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 50.528142] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 50.529039] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 50.530336] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 50.541332] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 50.543173] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 50.544690] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 50.725389] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 50.728648] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 50.733682] systemd[1]: Starting linger-users.service... machine # [ 50.782780] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 50.785976] systemd[1]: Finished linger-users.service. machine # [ 51.041398] nixos[3364]: finished switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.72 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 51.085741] sudo[3501]: 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 # [ 51.101459] sudo[3501]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 51.117341] sudo[3501]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 51.152719] sudo[3507]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 51.163797] sudo[3507]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 51.172653] sudo[3507]: 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.05 seconds) machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 51.202769] sudo[3513]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 51.213290] sudo[3513]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 51.221977] sudo[3513]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 51.250706] sudo[3519]: 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 # [ 51.260877] sudo[3519]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 51.268771] sudo[3519]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 51.294864] sudo[3525]: 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 # [ 51.304995] sudo[3525]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 51.312925] systemd[563]: Reload requested from client PID 3530 ('systemctl')... machine # [ 51.316796] systemd[563]: Reloading... machine # [ 51.425867] systemd[563]: Reloading finished in 110 ms. machine # [ 51.440174] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.453510] sudo[3525]: 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.19 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 51.481515] sudo[3535]: 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 # [ 51.491090] sudo[3535]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 51.504305] systemd[563]: Starting migrated.service... machine # [ 51.530796] systemd[563]: Finished migrated.service. machine # [ 51.534936] sudo[3535]: 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.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 51.562616] sudo[3543]: 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 # [ 51.572423] sudo[3543]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 51.583281] sudo[3543]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 51.610300] sudo[3549]: 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 # [ 51.619859] sudo[3549]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 51.627727] sudo[3549]: 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.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 51.653826] sudo[3555]: 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 # [ 51.663558] sudo[3555]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 51.679477] sudo[3555]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1 machine # [ 51.858798] nixos[3564]: switching to system configuration /nix/store/j24581044ha8x58mcxfbg6flgpa65ivn-nixos-system-machine-test machine # [ 51.863009] systemd[1]: Stopped target Remote File Systems. machine # [ 51.863863] systemd[1]: Stopped target Local File Systems. machine # [ 52.162085] systemd[1]: Reload requested from client PID 3564 ('.switch-to-conf') (unit backdoor.service)... machine # [ 52.164189] systemd[1]: Reloading... machine # [ 52.466503] systemd[1]: Reloading finished in 300 ms. machine # [ 52.567501] systemd[563]: Reexecution requested from client PID 3633 ('switch-to-confi')... machine # [ 52.569763] systemd[563]: Reexecuting. machine # [ 52.631397] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.748167] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.754969] systemd[563]: Reload requested from client PID 3633 ('switch-to-confi')... machine # [ 52.757616] systemd[563]: Reloading... machine # [ 52.870170] systemd[563]: Reloading finished in 111 ms. machine # [ 52.884213] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.893836] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 52.896090] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 52.898646] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 52.937205] systemd[563]: Reload requested from client PID 3645 ('systemctl') (unit nixos-activation.service)... machine # [ 52.939976] systemd[563]: Reloading... machine # [ 53.054878] systemd[563]: Reloading finished in 114 ms. machine # [ 53.072193] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.090011] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 53.093418] systemd[563]: Reload requested from client PID 3633 ('switch-to-confi')... machine # [ 53.094764] systemd[563]: Reloading... machine # [ 53.206503] systemd[563]: Reloading finished in 110 ms. machine # [ 53.224188] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.235186] systemd[563]: Reloading migrated.service... machine # [ 53.262176] systemd[563]: Reloaded migrated.service. machine # [ 53.269611] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 53.273797] systemd[1]: Stopping Reactivate sysinit units... machine # [ 53.274901] systemd[1]: Reached target Reactivate sysinit units. machine # [ 53.278135] systemd[1]: Reached target Remote File Systems. machine # [ 53.283805] systemd[1]: Reached target Local File Systems. machine # [ 53.287768] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 53.292250] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 53.293162] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 53.294433] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 53.307303] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 53.309352] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.310762] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.311989] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.313377] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 53.479521] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 53.484868] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 53.487233] systemd[1]: Starting linger-users.service... machine # [ 53.533370] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 53.536494] systemd[1]: Finished linger-users.service. machine # [ 53.792542] nixos[3564]: finished switching to system configuration /nix/store/j24581044ha8x58mcxfbg6flgpa65ivn-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 2.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 53.840672] sudo[3714]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 53.857598] sudo[3714]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 53.870962] sudo[3714]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 53.905817] sudo[3720]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 53.917435] sudo[3720]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 53.927152] sudo[3720]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 54.114774] nixos[3729]: switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test machine # [ 54.117447] systemd[1]: Stopped target Local File Systems. machine # [ 54.119957] systemd[1]: Stopped target Remote File Systems. machine # [ 54.400966] systemd[1]: Reload requested from client PID 3729 ('.switch-to-conf') (unit backdoor.service)... machine # [ 54.403111] systemd[1]: Reloading... machine # [ 54.733686] systemd[1]: Reloading finished in 329 ms. machine # [ 54.826965] systemd[563]: Stopped migrated.service. machine # [ 54.831090] systemd[563]: Reexecution requested from client PID 3798 ('switch-to-confi')... machine # [ 54.833211] systemd[563]: Reexecuting. machine # [ 54.887743] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.005312] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.012724] systemd[563]: Reload requested from client PID 3798 ('switch-to-confi')... machine # [ 55.017038] systemd[563]: Reloading... machine # [ 55.133431] systemd[563]: Reloading finished in 117 ms. machine # [ 55.148393] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.158568] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 55.161526] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 55.164097] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 55.200118] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 55.204676] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 55.208807] systemd[1]: Stopping Reactivate sysinit units... machine # [ 55.209862] systemd[1]: Reached target Reactivate sysinit units. machine # [ 55.213867] systemd[1]: Reached target Local File Systems. machine # [ 55.216925] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 55.224732] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 55.225772] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 55.227118] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 55.239442] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 55.246647] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.248976] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.250326] systemd[1]: Reached target Remote File Systems. machine # [ 55.251156] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.253027] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 55.435145] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 55.440860] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 55.443896] systemd[1]: Starting linger-users.service... machine # [ 55.489574] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 55.492985] systemd[1]: Finished linger-users.service. machine # [ 55.747212] nixos[3729]: finished switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.82 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 55.792528] sudo[3866]: 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 # [ 55.806986] sudo[3866]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 55.821403] sudo[3866]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 55.853278] sudo[3872]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 55.863985] sudo[3872]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 55.872816] sudo[3872]: 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.05 seconds) machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 55.902733] sudo[3878]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 55.913130] sudo[3878]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 55.921711] sudo[3878]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 55.950328] sudo[3884]: 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 # [ 55.960791] sudo[3884]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 55.969072] sudo[3884]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 55.997316] sudo[3890]: 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 # [ 56.006996] sudo[3890]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.016222] systemd[563]: Reload requested from client PID 3895 ('systemctl')... machine # [ 56.020223] systemd[563]: Reloading... machine # [ 56.134469] systemd[563]: Reloading finished in 115 ms. machine # [ 56.153839] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 56.162645] sudo[3890]: 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.19 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 56.191073] sudo[3900]: 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 # [ 56.201069] sudo[3900]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.212448] systemd[563]: Starting migrated.service... machine # [ 56.242973] systemd[563]: Finished migrated.service. machine # [ 56.247717] sudo[3900]: 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.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 56.275619] sudo[3908]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 56.285757] sudo[3908]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.296235] sudo[3908]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 56.323409] sudo[3914]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 56.333348] sudo[3914]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.341326] sudo[3914]: 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.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 56.368540] sudo[3920]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 56.378346] sudo[3920]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.394821] sudo[3920]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1 machine # [ 56.566778] nixos[3929]: switching to system configuration /nix/store/5q91xrzda1l6nzb4bgl9i97b0wqr1mhi-nixos-system-machine-test machine # [ 56.570938] systemd[1]: Stopped target Remote File Systems. machine # [ 56.571812] systemd[1]: Stopped target Local File Systems. machine # [ 56.845736] systemd[1]: Reload requested from client PID 3929 ('.switch-to-conf') (unit backdoor.service)... machine # [ 56.847822] systemd[1]: Reloading... machine # [ 57.159401] systemd[1]: Reloading finished in 310 ms. machine # [ 57.248107] systemd[563]: Reexecution requested from client PID 3998 ('switch-to-confi')... machine # [ 57.249570] systemd[563]: Reexecuting. machine # [ 57.307360] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.423372] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.430166] systemd[563]: Reload requested from client PID 3998 ('switch-to-confi')... machine # [ 57.433931] systemd[563]: Reloading... machine # [ 57.541116] systemd[563]: Reloading finished in 108 ms. machine # [ 57.556196] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.566077] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 57.568072] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 57.570696] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 57.607958] systemd[563]: Reload requested from client PID 4010 ('systemctl') (unit nixos-activation.service)... machine # [ 57.610858] systemd[563]: Reloading... machine # [ 57.727043] systemd[563]: Reloading finished in 115 ms. machine # [ 57.744188] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.762147] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 57.765023] systemd[563]: Reload requested from client PID 3998 ('switch-to-confi')... machine # [ 57.768858] systemd[563]: Reloading... machine # [ 57.877063] systemd[563]: Reloading finished in 109 ms. machine # [ 57.888215] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.902529] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 57.905913] systemd[1]: Stopping Reactivate sysinit units... machine # [ 57.906956] systemd[1]: Reached target Reactivate sysinit units. machine # [ 57.909278] systemd[1]: Reached target Remote File Systems. machine # [ 57.914393] systemd[1]: Reached target Local File Systems. machine # [ 57.917779] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 57.922021] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 57.922952] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 57.930811] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 57.937684] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 57.939423] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 57.942344] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 57.943643] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 57.945071] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 58.117850] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 58.123240] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 58.126065] systemd[1]: Starting linger-users.service... machine # [ 58.174247] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 58.177745] systemd[1]: Finished linger-users.service. machine # [ 58.433810] nixos[3929]: finished switching to system configuration /nix/store/5q91xrzda1l6nzb4bgl9i97b0wqr1mhi-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 2.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 58.482257] sudo[4077]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 58.497887] sudo[4077]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 58.512603] sudo[4077]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 58.549915] sudo[4083]: 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 # [ 58.561827] sudo[4083]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 58.571864] sudo[4083]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 58.757690] nixos[4092]: switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test machine # [ 58.760438] systemd[1]: Stopped target Remote File Systems. machine # [ 58.762942] systemd[1]: Stopped target Local File Systems. machine # [ 59.039192] systemd[1]: Reload requested from client PID 4092 ('.switch-to-conf') (unit backdoor.service)... machine # [ 59.041307] systemd[1]: Reloading... machine # [ 59.357357] systemd[1]: Reloading finished in 314 ms. machine # [ 59.446763] systemd[563]: Stopped migrated.service. machine # [ 59.451325] systemd[563]: Reexecution requested from client PID 4161 ('switch-to-confi')... machine # [ 59.453542] systemd[563]: Reexecuting. machine # [ 59.512338] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.631205] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.638516] systemd[563]: Reload requested from client PID 4161 ('switch-to-confi')... machine # [ 59.641450] systemd[563]: Reloading... machine # [ 59.755928] systemd[563]: Reloading finished in 113 ms. machine # [ 59.772261] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.781925] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 59.784699] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 59.787072] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 59.822854] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 59.827509] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 59.830439] systemd[1]: Stopping Reactivate sysinit units... machine # [ 59.831434] systemd[1]: Reached target Reactivate sysinit units. machine # [ 59.836810] systemd[1]: Reached target Local File Systems. machine # [ 59.837825] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 59.849179] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 59.850372] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 59.851700] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 59.866879] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 59.868832] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 59.870189] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 59.871519] systemd[1]: Reached target Remote File Systems. machine # [ 59.872878] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 59.874425] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 60.053637] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 60.056176] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 60.061633] systemd[1]: Starting linger-users.service... machine # [ 60.110073] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 60.113082] systemd[1]: Finished linger-users.service. machine # [ 60.370012] nixos[4092]: finished switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.80 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 60.414561] sudo[4229]: 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 # [ 60.429670] sudo[4229]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 60.445408] sudo[4229]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user machine # [ 60.480628] sudo[4235]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user machine # [ 60.491843] sudo[4235]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 60.501722] sudo[4235]: 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.06 seconds) machine: must succeed: sudo -u usertest cp /nix/store/kbn5jd7gqb07qksa4hgqhsx409fjnskk-migrated.service ~usertest/.local/share/systemd/user/migrated.service machine # [ 60.533062] sudo[4241]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/kbn5jd7gqb07qksa4hgqhsx409fjnskk-migrated.service /home/usertest/.local/share/systemd/user/migrated.service machine # [ 60.544832] sudo[4241]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 60.553896] sudo[4241]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/kbn5jd7gqb07qksa4hgqhsx409fjnskk-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 60.581233] sudo[4247]: 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 # [ 60.591071] sudo[4247]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 60.599662] systemd[563]: Reload requested from client PID 4252 ('systemctl')... machine # [ 60.603866] systemd[563]: Reloading... machine # [ 60.712539] systemd[563]: Reloading finished in 110 ms. machine # [ 60.728188] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 60.741556] sudo[4247]: 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.19 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 60.769868] sudo[4257]: 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 # [ 60.779595] sudo[4257]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 60.795052] systemd[563]: Starting migrated.service... machine # [ 60.823219] systemd[563]: Finished migrated.service. machine # [ 60.827807] sudo[4257]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 60.855529] sudo[4265]: 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 # [ 60.865439] sudo[4265]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 60.875839] sudo[4265]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 60.903194] sudo[4271]: 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 # [ 60.913082] sudo[4271]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 60.921039] sudo[4271]: 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.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 60.947757] sudo[4277]: 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 # [ 60.958060] sudo[4277]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 60.978330] sudo[4277]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 61.156673] nixos[4286]: switching to system configuration /nix/store/zcnavbxqjwcm6qww1p9w6q3laflll89k-nixos-system-machine-test machine # [ 61.159673] systemd[1]: Stopped target Remote File Systems. machine # [ 61.162111] systemd[1]: Stopped target Local File Systems. machine # [ 61.443149] systemd[1]: Reload requested from client PID 4286 ('.switch-to-conf') (unit backdoor.service)... machine # [ 61.445511] systemd[1]: Reloading... machine # [ 61.757730] systemd[1]: Reloading finished in 311 ms. machine # [ 61.848633] systemd[563]: Reexecution requested from client PID 4355 ('switch-to-confi')... machine # [ 61.853126] systemd[563]: Reexecuting. machine # [ 61.907091] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.022248] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.029753] systemd[563]: Reload requested from client PID 4355 ('switch-to-confi')... machine # [ 62.031232] systemd[563]: Reloading... machine # [ 62.139531] systemd[563]: Reloading finished in 107 ms. machine # [ 62.156185] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.167997] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 62.169101] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 62.172151] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 62.200988] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 62.203245] systemd[563]: Reload requested from client PID 4355 ('switch-to-confi')... machine # [ 62.207585] systemd[563]: Reloading... machine # [ 62.317319] systemd[563]: Reloading finished in 111 ms. machine # [ 62.332186] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.340685] systemd[563]: Stopped migrated.service. machine # [ 62.343051] systemd[563]: Stopping migrated.service... machine # [ 62.345102] systemd[563]: Starting migrated.service... machine # [ 62.370774] systemd[563]: Finished migrated.service. machine # [ 62.377360] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 62.380946] systemd[1]: Stopping Reactivate sysinit units... machine # [ 62.381866] systemd[1]: Reached target Reactivate sysinit units. machine # [ 62.382744] systemd[1]: Reached target Local File Systems. machine # [ 62.385540] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.386960] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 62.391420] systemd[1]: Reached target Remote File Systems. machine # [ 62.398438] systemd[1]: Starting linger-users.service... machine # [ 62.404067] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 62.412774] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 62.413753] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 62.415171] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 62.424732] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 62.426451] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.427764] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.483885] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 62.486822] systemd[1]: Finished linger-users.service. machine # [ 62.615368] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 62.618964] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 62.873979] nixos[4286]: finished switching to system configuration /nix/store/zcnavbxqjwcm6qww1p9w6q3laflll89k-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.90 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 62.916662] sudo[4429]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 62.931388] sudo[4429]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 62.946140] sudo[4429]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 62.979078] sudo[4435]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 62.990588] sudo[4435]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 63.008772] sudo[4435]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 63.037430] sudo[4441]: 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 # [ 63.047011] sudo[4441]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 63.055120] sudo[4441]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 63.233890] nixos[4450]: switching to system configuration /nix/store/zcnavbxqjwcm6qww1p9w6q3laflll89k-nixos-system-machine-test machine # [ 63.237980] systemd[1]: Stopped target Remote File Systems. machine # [ 63.238933] systemd[1]: Stopped target Local File Systems. machine # [ 63.510104] systemd[1]: Reload requested from client PID 4450 ('.switch-to-conf') (unit backdoor.service)... machine # [ 63.512227] systemd[1]: Reloading... machine # [ 63.825164] systemd[1]: Reloading finished in 311 ms. machine # [ 63.912460] systemd[563]: Reexecution requested from client PID 4519 ('switch-to-confi')... machine # [ 63.915888] systemd[563]: Reexecuting. machine # [ 63.971610] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 64.090804] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 64.098922] systemd[563]: Reload requested from client PID 4519 ('switch-to-confi')... machine # [ 64.102475] systemd[563]: Reloading... machine # [ 64.214732] systemd[563]: Reloading finished in 113 ms. machine # [ 64.232255] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 64.243135] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 64.245814] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 64.248261] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 64.283985] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 64.288671] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 64.292912] systemd[1]: Stopping Reactivate sysinit units... machine # [ 64.294028] systemd[1]: Reached target Reactivate sysinit units. machine # [ 64.295854] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 64.298977] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 64.304818] systemd[1]: Reached target Local File Systems. machine # [ 64.305740] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 64.309763] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 64.310789] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 64.320458] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 64.322889] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 64.331923] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 64.333569] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 64.335006] systemd[1]: Reached target Remote File Systems. machine # [ 64.510792] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 64.513559] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 64.519017] systemd[1]: Starting linger-users.service... machine # [ 64.565747] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 64.569263] systemd[1]: Finished linger-users.service. machine # [ 64.826237] nixos[4450]: finished switching to system configuration /nix/store/zcnavbxqjwcm6qww1p9w6q3laflll89k-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.78 seconds) machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd machine # [ 64.872771] sudo[4587]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd machine # [ 64.887773] sudo[4587]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 64.899972] sudo[4587]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 64.934217] sudo[4593]: 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 # [ 64.944926] sudo[4593]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 64.954064] systemd[563]: Reload requested from client PID 4598 ('systemctl')... machine # [ 64.958238] systemd[563]: Reloading... machine # [ 65.068933] systemd[563]: Reloading finished in 111 ms. machine # [ 65.084286] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.095671] sudo[4593]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.20 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 65.278766] nixos[4606]: switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test machine # [ 65.281332] systemd[1]: Stopped target Local File Systems. machine # [ 65.283358] systemd[1]: Stopped target Remote File Systems. machine # [ 65.570886] systemd[1]: Reload requested from client PID 4606 ('.switch-to-conf') (unit backdoor.service)... machine # [ 65.573100] systemd[1]: Reloading... machine # [ 65.891078] systemd[1]: Reloading finished in 316 ms. machine # [ 65.977387] systemd[563]: Stopped migrated.service. machine # [ 65.980581] systemd[563]: Reexecution requested from client PID 4675 ('switch-to-confi')... machine # [ 65.984088] systemd[563]: Reexecuting. machine # [ 66.034992] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 66.145015] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 66.152108] systemd[563]: Reload requested from client PID 4675 ('switch-to-confi')... machine # [ 66.153482] systemd[563]: Reloading... machine # [ 66.257845] systemd[563]: Reloading finished in 102 ms. machine # [ 66.273594] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 66.282700] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 66.285091] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 66.287263] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 66.321875] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 66.326591] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 66.328973] systemd[1]: Stopping Reactivate sysinit units... machine # [ 66.329964] systemd[1]: Reached target Reactivate sysinit units. machine # [ 66.334651] systemd[1]: Reached target Remote File Systems. machine # [ 66.338732] systemd[1]: Reached target Local File Systems. machine # [ 66.341773] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 66.348471] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 66.349531] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 66.350845] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 66.365684] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 66.367494] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 66.369078] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 66.370516] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 66.371838] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 66.535989] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 66.538093] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 66.543572] systemd[1]: Starting linger-users.service... machine # [ 66.587314] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 66.590433] systemd[1]: Finished linger-users.service. machine # [ 66.845182] nixos[4606]: finished switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.75 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 66.880824] sudo[4743]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 66.893480] sudo[4743]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 66.902040] sudo[4743]: 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.05 seconds) machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 66.933046] sudo[4749]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 66.943612] sudo[4749]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 66.952652] sudo[4749]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 66.981685] sudo[4755]: 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 # [ 66.993050] sudo[4755]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 67.000765] sudo[4755]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 67.028309] sudo[4761]: 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 # [ 67.037849] sudo[4761]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 67.046958] systemd[563]: Reload requested from client PID 4766 ('systemctl')... machine # [ 67.051086] systemd[563]: Reloading... machine # [ 67.161515] systemd[563]: Reloading finished in 111 ms. machine # [ 67.172174] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 67.184235] sudo[4761]: 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.18 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 67.212651] sudo[4771]: 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 # [ 67.222424] sudo[4771]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 67.235976] systemd[563]: Starting migrated.service... machine # [ 67.264225] systemd[563]: Finished migrated.service. machine # [ 67.269174] sudo[4771]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 67.302852] sudo[4779]: 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 # [ 67.313858] sudo[4779]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 67.325825] sudo[4779]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 67.357343] sudo[4785]: 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 # [ 67.367385] sudo[4785]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 67.376252] sudo[4785]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 67.406368] sudo[4791]: 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 # [ 67.416904] sudo[4791]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 67.434850] sudo[4791]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 67.616481] nixos[4800]: switching to system configuration /nix/store/zcnavbxqjwcm6qww1p9w6q3laflll89k-nixos-system-machine-test machine # [ 67.619423] systemd[1]: Stopped target Local File Systems. machine # [ 67.621874] systemd[1]: Stopped target Remote File Systems. machine # [ 67.890355] systemd[1]: Reload requested from client PID 4800 ('.switch-to-conf') (unit backdoor.service)... machine # [ 67.892624] systemd[1]: Reloading... machine # [ 68.203274] systemd[1]: Reloading finished in 309 ms. machine # [ 68.289674] systemd[563]: Reexecution requested from client PID 4869 ('switch-to-confi')... machine # [ 68.293385] systemd[563]: Reexecuting. machine # [ 68.347247] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.462159] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.469018] systemd[563]: Reload requested from client PID 4869 ('switch-to-confi')... machine # [ 68.471660] systemd[563]: Reloading... machine # [ 68.579065] systemd[563]: Reloading finished in 106 ms. machine # [ 68.593785] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.601318] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 68.603775] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 68.606124] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 68.635886] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 68.638686] systemd[563]: Reload requested from client PID 4869 ('switch-to-confi')... machine # [ 68.642714] systemd[563]: Reloading... machine # [ 68.752121] systemd[563]: Reloading finished in 111 ms. machine # [ 68.769821] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.781042] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 68.783103] systemd[1]: Stopping Reactivate sysinit units... machine # [ 68.785795] systemd[1]: Reached target Reactivate sysinit units. machine # [ 68.788980] systemd[1]: Reached target Remote File Systems. machine # [ 68.791758] systemd[1]: Reached target Local File Systems. machine # [ 68.796225] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 68.805196] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 68.806339] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 68.807614] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 68.818289] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 68.820091] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 68.821412] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 68.822663] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 68.823974] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 68.990714] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 68.995872] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 68.998379] systemd[1]: Starting linger-users.service... machine # [ 69.033258] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 69.036649] systemd[1]: Finished linger-users.service. machine # [ 69.290895] nixos[4800]: finished switching to system configuration /nix/store/zcnavbxqjwcm6qww1p9w6q3laflll89k-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.86 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 69.333435] sudo[4941]: 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 # [ 69.348726] sudo[4941]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 69.372119] sudo[4941]: 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: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 69.407409] sudo[4947]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 69.419186] sudo[4947]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 69.428127] sudo[4947]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds) machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd machine # [ 69.457260] sudo[4953]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd machine # [ 69.466660] sudo[4953]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 69.474806] sudo[4953]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 69.501418] sudo[4959]: 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 # [ 69.510899] sudo[4959]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 69.519528] systemd[563]: Reload requested from client PID 4964 ('systemctl')... machine # [ 69.521928] systemd[563]: Reloading... machine # [ 69.632265] systemd[563]: Reloading finished in 108 ms. machine # [ 69.644167] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.658051] sudo[4959]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.18 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service machine # [ 69.686144] sudo[4969]: 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 # [ 69.696767] sudo[4969]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 69.707868] systemd[563]: Stopped migrated.service. machine # [ 69.712074] sudo[4969]: 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.05 seconds) machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 69.884911] nixos[4978]: switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test machine # [ 69.890243] systemd[1]: Stopped target Remote File Systems. machine # [ 69.891205] systemd[1]: Stopped target Local File Systems. machine # [ 70.152639] systemd[1]: Reload requested from client PID 4978 ('.switch-to-conf') (unit backdoor.service)... machine # [ 70.155696] systemd[1]: Reloading... machine # [ 70.479384] systemd[1]: Reloading finished in 322 ms. machine # [ 70.570500] systemd[563]: Reexecution requested from client PID 5047 ('switch-to-confi')... machine # [ 70.571963] systemd[563]: Reexecuting. machine # [ 70.623162] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 70.734865] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 70.741948] systemd[563]: Reload requested from client PID 5047 ('switch-to-confi')... machine # [ 70.744541] systemd[563]: Reloading... machine # [ 70.853990] systemd[563]: Reloading finished in 108 ms. machine # [ 70.868185] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 70.877132] systemd[563]: Stopped Run user-specific NixOS activation. machine # [ 70.879713] systemd[563]: Stopping Run user-specific NixOS activation... machine # [ 70.882247] systemd[563]: Starting Run user-specific NixOS activation... machine # [ 70.912823] systemd[563]: Finished Run user-specific NixOS activation. machine # [ 70.917023] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 70.921212] systemd[1]: Stopping Reactivate sysinit units... machine # [ 70.922201] systemd[1]: Reached target Reactivate sysinit units. machine # [ 70.923743] systemd[1]: Reached target Remote File Systems. machine # [ 70.928249] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 70.929692] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 70.935824] systemd[1]: Starting linger-users.service... machine # [ 70.941149] systemd[1]: Reached target Local File Systems. machine # [ 70.942190] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 70.956153] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 70.957221] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 70.958609] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 70.965240] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 70.967124] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 70.968744] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 71.026632] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 71.031158] systemd[1]: Finished linger-users.service. machine # [ 71.158576] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 71.161676] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 71.417488] nixos[4978]: finished switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.71 seconds) (finished: subtest: user services, in 48.30 seconds) (finished: run the VM test script, in 72.17 seconds) test script finished in 72.25s cleanup kill QemuMachine (pid 45) machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14) machine # [2026-09-27T03:14:26Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-27T03:14:26Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-27T03:14:26Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.13 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