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.tAn7DClphE', fmt=raw size=1073741824 machine # mke2fs 1.47.4 (6-Mar-2025) machine # Discarding device blocks: 0/262144 done machine # Creating filesystem with 262144 4k blocks and 65536 inodes machine # Filesystem UUID: 95ddb635-9197-408d-9497-a2308c5f2133 machine # Superblock backups stored on blocks: machine # 32768, 98304, 163840, 229376 machine # machine # Allocating group tables: 0/8 done machine # Writing inode tables: 0/8 done machine # Creating journal (8192 blocks): done machine # Writing superblocks and filesystem accounting information: 0/8 done machine # machine # Virtualisation disk image created. machine # Starting virtiofs daemons... machine # [2026-10-01T03:46:09Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-10-01T03:46:09Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-10-01T03:46:09Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-10-01T03:46:09Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-10-01T03:46:09Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-10-01T03:46:09Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-10-01T03:46:09Z INFO virtiofsd] Client connected, servicing requests machine # [2026-10-01T03:46:09Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-10-01T03:46:09Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-10-01T03:46:09Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-10-01T03:46:09Z INFO virtiofsd] Client connected, servicing requests machine # [2026-10-01T03:46:09Z INFO virtiofsd] Client connected, servicing requests machine: QEMU running (pid 45) machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] machine # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 2026 machine # [ 0.000000] KASLR enabled machine # [ 0.000000] random: crng init done machine # [ 0.000000] Machine model: linux,dummy-virt machine # [ 0.000000] efi: UEFI not found. machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f] machine # [ 0.000000] Zone ranges: machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] DMA32 empty machine # [ 0.000000] Normal empty machine # [ 0.000000] Device empty machine # [ 0.000000] Movable zone start for each node machine # [ 0.000000] Early memory node ranges machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000 machine # [ 0.000000] psci: probing for conduit method from DT. machine # [ 0.000000] psci: PSCIv1.3 detected in firmware. machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs machine # [ 0.000000] psci: Trusted OS migration not required machine # [ 0.000000] psci: SMC Calling Convention v1.1 machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) machine # [ 0.000000] percpu: Embedded 76 pages/cpu 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/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/init regInfo=/nix/.ro-store/x6qzqgkg30227m8cx6na7by50hbw48gn-closure-info/registration console=ttyAMA0,115200n8 console=tty0 machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/x6qzqgkg30227m8cx6na7by50hbw48gn-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 75026 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 @44ce0000 (indirect, esz 8, psz 64K, shr 1) machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (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.000106] arm-pv: using stolen time PV machine # [ 0.004110] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.004380] Console: colour dummy device 80x25 machine # [ 0.004390] printk: legacy console [tty0] enabled machine # [ 0.004596] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) machine # [ 0.004604] pid_max: default: 32768 minimum: 301 machine # [ 0.004711] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.005363] landlock: Up and running. machine # [ 0.005367] Yama: becoming mindful. machine # [ 0.008585] LSM support for eBPF active machine # [ 0.008956] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.008985] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.011142] rcu: Hierarchical SRCU implementation. machine # [ 0.011151] rcu: Max phase no-delay instances is 1000. machine # [ 0.012530] fsl-mc MSI: its@8080000 domain created machine # [ 0.012659] EFI services will not be available. machine # [ 0.012784] smp: Bringing up secondary CPUs ... machine # [ 0.012795] smp: Brought up 1 node, 1 CPU machine # [ 0.012798] SMP: Total of 1 processors activated. machine # [ 0.012801] CPU: All CPU(s) started at EL1 machine # [ 0.012812] CPU features: detected: Branch Target Identification machine # [ 0.012822] CPU features: detected: ARMv8.4 Translation Table Level machine # [ 0.012825] CPU features: detected: Instruction cache invalidation not required for I/D coherence machine # [ 0.012829] CPU features: detected: Data cache clean to the PoU not required for I/D coherence machine # [ 0.012833] CPU features: detected: Common not Private translations machine # [ 0.012836] CPU features: detected: CRC32 instructions machine # [ 0.012839] CPU features: detected: Data cache clean to Point of Deep Persistence machine # [ 0.012843] CPU features: detected: Data cache clean to Point of Persistence machine # [ 0.012846] CPU features: detected: Data independent timing control (DIT) machine # [ 0.012849] CPU features: detected: E0PD machine # [ 0.012852] CPU features: detected: Enhanced Counter Virtualization machine # [ 0.012855] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.012858] CPU features: detected: Enhanced Virtualization Traps machine # [ 0.012862] CPU features: detected: Fine Grained Traps machine # [ 0.012865] CPU features: detected: Generic authentication (architected QARMA5 algorithm) machine # [ 0.012870] CPU features: detected: RCpc load-acquire (LDAPR) machine # [ 0.012873] CPU features: detected: LSE atomic instructions machine # [ 0.012876] CPU features: detected: Privileged Access Never machine # [ 0.012879] CPU features: detected: PMUv3 machine # [ 0.012881] CPU features: detected: RAS Extension Support machine # [ 0.012884] CPU features: detected: RASv1p1 Extension Support machine # [ 0.012887] CPU features: detected: Random Number Generator machine # [ 0.012890] CPU features: detected: Speculation barrier (SB) machine # [ 0.012892] CPU features: detected: Stage-2 Force Write-Back machine # [ 0.012895] CPU features: detected: TLB range maintenance instructions machine # [ 0.012900] CPU features: detected: Speculative Store Bypassing Safe (SSBS) machine # [ 0.012975] alternatives: applying system-wide alternatives machine # [ 0.016337] CPU features: detected: BBM Level 2 without TLB conflict abort machine # [ 0.016609] Memory: 893668K/1048576K available (24512K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 113608K reserved, 32768K cma-reserved) machine # [ 0.017027] devtmpfs: initialized machine # [ 0.018936] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.018961] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.019166] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL machine # [ 0.019171] 0 pages in range for non-PLT usage machine # [ 0.019172] 508208 pages in range for PLT usage machine # [ 0.019293] pinctrl core: initialized pinctrl subsystem machine # [ 0.020195] DMI not present or invalid. machine # [ 0.028395] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.031122] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.031288] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.031462] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.031498] audit: initializing netlink subsys (disabled) machine # [ 0.032164] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.032167] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.032170] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.032173] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.032176] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.032206] audit: type=2000 audit(0.028:1): state=initialized audit_enabled=0 res=1 machine # [ 0.032218] cpuidle: using governor ladder machine # [ 0.032224] cpuidle: using governor menu machine # [ 0.032464] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.032482] ASID allocator initialised with 65536 entries machine # [ 0.033834] Serial: AMBA PL011 UART driver machine # [ 0.040019] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 machine # [ 0.040189] printk: console [ttyAMA0] enabled machine # [ 0.176448] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.176462] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.176465] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages machine # [ 0.176468] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.176471] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.176474] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.176478] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages machine # [ 0.176480] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.177959] fbcon: Taking over console machine # [ 0.177971] ACPI: Interpreter disabled. machine # [ 0.188236] iommu: Default domain type: Translated machine # [ 0.188244] iommu: DMA domain TLB invalidation policy: strict mode machine # [ 0.190608] SCSI subsystem initialized machine # [ 0.192868] usbcore: registered new interface driver usbfs machine # [ 0.192907] usbcore: registered new interface driver hub machine # [ 0.192926] usbcore: registered new device driver usb machine # [ 0.193277] pps_core: LinuxPPS API ver. 1 registered machine # [ 0.193280] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti machine # [ 0.193289] PTP clock support registered machine # [ 0.193330] EDAC MC: Ver: 3.0.0 machine # [ 0.199167] scmi_core: SCMI protocol bus registered machine # [ 0.199601] FPGA manager framework machine # [ 0.201918] vgaarb: loaded machine # [ 0.205040] clocksource: Switched to clocksource arch_sys_counter machine # [ 0.211918] VFS: Disk quotas dquot_6.6.0 machine # [ 0.211959] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.214855] netfs: FS-Cache loaded machine # [ 0.214993] pnp: PnP ACPI: disabled machine # [ 0.220942] NET: Registered PF_INET protocol family machine # [ 0.221834] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.255382] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.255435] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.255462] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.255518] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.255593] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.255698] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.255773] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.255823] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.255918] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.255937] NET: Registered PF_XDP protocol family machine # [ 0.255957] PCI: CLS 0 bytes, default 64 machine # [ 0.256323] Trying to unpack rootfs image as initramfs... machine # [ 0.273083] kvm [1]: HYP mode not available machine # [ 0.441722] Initialise system trusted keyrings machine # [ 0.441884] workingset: timestamp_bits=42 max_order=18 bucket_order=0 machine # [ 0.442337] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.442411] 9p: Installing v9fs 9p2000 file system support machine # [ 0.470810] Key type asymmetric registered machine # [ 0.470832] Asymmetric key parser 'x509' registered machine # [ 0.470920] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.471085] io scheduler mq-deadline registered machine # [ 0.471090] io scheduler kyber registered machine # [ 0.483751] pl061_gpio 9030000.pl061: PL061 GPIO chip registered machine # [ 0.485900] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.486450] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: machine # [ 0.486466] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 machine # [ 0.486475] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 machine # [ 0.486481] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 machine # [ 0.486507] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits machine # [ 0.486530] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.486651] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.486658] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.486662] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.486665] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.486668] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] machine # [ 0.486761] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.487248] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.487469] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.487482] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.487512] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.487526] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.488032] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.488233] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.488249] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.488275] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.488782] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.488985] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] machine # [ 0.488998] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.489027] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.522979] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.523201] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.523215] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.523246] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.523260] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.523766] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.523971] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.523999] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.524489] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.524687] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.524714] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.535202] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.535399] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] machine # [ 0.535726] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.535935] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.535963] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.536470] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.536679] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.536708] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.544890] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.546190] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.546233] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.546759] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.547043] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.547056] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.547087] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.547593] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.547797] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.547810] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.547839] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.548515] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.548521] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.548525] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.548572] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.548619] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned machine # [ 0.548669] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned machine # [ 0.548718] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.548764] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.548812] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.548858] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned machine # [ 0.548903] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned machine # [ 0.548949] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned machine # [ 0.549024] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.577168] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.577208] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.577229] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned machine # [ 0.577250] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned machine # [ 0.577270] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned machine # [ 0.577291] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned machine # [ 0.577312] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned machine # [ 0.577333] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned machine # [ 0.577355] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned machine # [ 0.577381] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned machine # [ 0.577402] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned machine # [ 0.577423] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned machine # [ 0.577442] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned machine # [ 0.577461] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned machine # [ 0.577480] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned machine # [ 0.577500] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned machine # [ 0.577519] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned machine # [ 0.577539] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned machine # [ 0.577569] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] machine # [ 0.577574] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] machine # [ 0.577577] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] machine # [ 0.578557] pci 0000:00:07.0: enabling device (0000 -> 0002) machine # [ 0.624964] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) machine # [ 0.634946] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) machine # [ 0.638867] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) machine # [ 0.641015] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) machine # [ 0.651517] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) machine # [ 0.661491] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) machine # [ 0.663887] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.667734] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) machine # [ 0.677784] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.680171] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) machine # [ 0.684532] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) machine # [ 0.697787] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.703510] msm_serial: driver initialized machine # [ 0.703660] SuperH (H)SCI(F) driver initialized machine # [ 0.703719] STM32 USART driver initialized machine # [ 0.743701] loop: module loaded machine # [ 0.743931] virtio_blk virtio2: 1/0/0 default/read/poll queues machine # [ 0.744875] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 0.749815] megasas: 07.734.00.00-rc1 machine # [ 0.750781] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] machine # [ 0.752953] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.753010] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.765994] Using buffer write method machine # [ 0.766052] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] machine # [ 0.768028] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.768048] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.781712] Using buffer write method machine # [ 0.781745] Concatenating MTD devices: machine # [ 0.781747] (0): "0.flash" machine # [ 0.781750] (1): "0.flash" machine # [ 0.781752] into device "0.flash" machine # [ 1.100637] Freeing initrd memory: 26428K machine # [ 1.107505] tun: Universal TUN/TAP device driver, 1.6 machine # [ 1.111905] thunder_xcv, ver 1.0 machine # [ 1.111937] thunder_bgx, ver 1.0 machine # [ 1.111959] nicpf, ver 1.0 machine # [ 1.112519] e1000: Intel(R) PRO/1000 Network Driver machine # [ 1.112523] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 1.112549] e1000e: Intel(R) PRO/1000 Network Driver machine # [ 1.112560] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. machine # [ 1.112586] igb: Intel(R) Gigabit Ethernet Network Driver machine # [ 1.112590] igb: Copyright (c) 2007-2014 Intel Corporation. machine # [ 1.112609] igbvf: Intel(R) Gigabit Virtual Function Network Driver machine # [ 1.112612] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 1.112746] sky2: driver version 1.30 machine # [ 1.122570] usbcore: registered new interface driver usb-storage machine # [ 1.122665] usbcore: registered new interface driver usbserial_generic machine # [ 1.122677] usbserial: USB Serial support registered for generic machine # [ 1.123294] hv_vmbus: registering driver hyperv_keyboard machine # [ 1.124649] ehci-pci 0000:00:07.0: EHCI Host Controller machine # [ 1.124669] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 machine # [ 1.124844] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 machine # [ 1.131020] rtc-pl031 9010000.pl031: registered as rtc0 machine # [ 1.131047] rtc-pl031 9010000.pl031: setting system clock to 2026-10-01T03:46:12 UTC (1790826372) machine # [ 1.131474] i2c_dev: i2c /dev entries driver machine # [ 1.133116] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 machine # [ 1.133454] hub 1-0:1.0: USB hub found machine # [ 1.133471] hub 1-0:1.0: 6 ports detected machine # [ 1.140284] sdhci: Secure Digital Host Controller Interface driver machine # [ 1.140291] sdhci: Copyright(c) Pierre Ossman machine # [ 1.140575] Synopsys Designware Multimedia Card Interface Driver machine # [ 1.140959] sdhci-pltfm: SDHCI platform and OF driver helper machine # [ 1.145901] hid: raw HID events driver (C) Jiri Kosina machine # [ 1.146150] usbcore: registered new interface driver usbhid machine # [ 1.146155] usbhid: USB HID core driver machine # [ 1.147373] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available machine # [ 1.149028] drop_monitor: Initializing network drop monitor service machine # [ 1.153304] NET: Registered PF_INET6 protocol family machine # [ 1.153941] Segment Routing with IPv6 machine # [ 1.153954] In-situ OAM (IOAM) with IPv6 machine # [ 1.153987] NET: Registered PF_PACKET protocol family machine # [ 1.154051] 9pnet: Installing 9P2000 support machine # [ 1.154094] Key type dns_resolver registered machine # [ 1.164017] registered taskstats version 1 machine # [ 1.164170] Loading compiled-in X.509 certificates machine # [ 1.173258] Demotion targets for Node 0: null machine # [ 1.173390] Key type .fscrypt registered machine # [ 1.173394] Key type fscrypt-provisioning registered machine # [ 1.173501] ima: No TPM chip found, activating TPM-bypass! machine # [ 1.173520] ima: Allocated hash algorithm: sha1 machine # [ 1.173542] ima: No architecture policies found machine # [ 1.174486] input: gpio-keys as /devices/platform/gpio-keys/input/input0 machine # [ 1.200661] clk: Disabling unused clocks machine # [ 1.200683] PM: genpd: Disabling unused power domains machine # [ 1.205039] Freeing unused kernel memory: 4736K machine # [ 1.205872] Run /init as init process machine # [ 1.224394] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.225005] systemd[1]: systemd 263-889bc48 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.225020] systemd[1]: Detected virtualization qemu. machine # [ 1.230655] systemd[1]: Detected architecture arm64. machine # [ 1.230662] systemd[1]: Running in initrd. machine # [ 1.231008] systemd[1]: Initializing machine ID from random generator. machine # [ 1.231118] systemd[1]: Hostname set to . machine # [ 1.334689] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.381214] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 1.396871] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.407371] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.407574] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.407603] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.407624] systemd[1]: Reached target Path Units. machine # [ 1.407638] systemd[1]: Reached target Slice Units. machine # [ 1.407653] systemd[1]: Reached target Swaps. machine # [ 1.407667] systemd[1]: Reached target Timer Units. machine # [ 1.407804] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.407929] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.408049] systemd[1]: Listening on Journal Sockets. machine # [ 1.408138] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.408153] systemd[1]: Reached target Socket Units. machine # [ 1.418787] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.418871] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.424973] systemd[1]: Mounting Kernel Configuration File System... machine # [ 1.437430] systemd[1]: Starting Journal Service... machine # [ 1.463531] systemd[1]: Starting Load Kernel Modules... machine # [ 1.463651] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.479659] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.493440] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.498329] systemd-journald[71]: Collecting audit messages is disabled. machine # [ 1.513329] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.513660] systemd[1]: Mounted Kernel Configuration File System. machine # [ 1.574079] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.576407] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.578788] 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.602857] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.617189] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.628243] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 machine # [ 1.628519] [drm] features: -virgl +edid -resource_blob -host_visible machine # [ 1.628522] [drm] features: -context_init machine # [ 1.649984] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.650251] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.650277] systemd[1]: Reached target Local File Systems. machine # [ 1.656177] [drm] number of scanouts: 1 machine # [ 1.656195] [drm] number of cap sets: 0 machine # [ 1.656815] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic machine # [ 1.656827] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 machine # [ 1.669293] 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.670103] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.684804] systemd-modules-load[73]: Inserted module 'dm_mod' machine # [ 1.686206] systemd-modules-load[73]: Module 'virtio_balloon' is built in machine # [ 1.687468] systemd-modules-load[73]: Module 'virtio_console' is built in machine # [ 1.705787] systemd[1]: Started Journal Service. machine # [ 1.713148] Console: switching to colour frame buffer device 160x50 machine # [ 1.714005] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device machine # [ 1.703824] systemd-modules-load[73]: Inserted module 'virtio_gpu' machine # [ 1.708490] systemd-modules-load[73]: Module 'virtio_rng' is built in machine # [ 1.721420] systemd[1]: Starting Create System Files and Directories... machine # [ 1.723393] systemd[1]: Finished Load Kernel Modules. machine # [ 1.768180] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.771119] systemd-udevd[78]: Using default interface naming scheme 'v261'. machine # [ 1.789308] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.793802] systemd[1]: Finished Create System Files and Directories. machine # [ 1.815252] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.825815] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 1.913661] systemd[1]: Starting Virtual Console Setup... machine # [ 1.952270] 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.952568] 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.020517] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.024140] systemd[1]: Finished Virtual Console Setup. machine # [ 2.788552] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.790528] systemd[1]: Reached target System Initialization. machine # [ 2.791484] systemd[1]: Reached target Basic System. machine # [ 2.969430] (udev-worker)[89]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.974189] (udev-worker)[89]: Network interface NamePolicy= disabled on kernel command line. machine # [ 3.062962] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 3.067442] systemd[1]: Reached target Initrd Root Device. machine # [ 3.072489] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 3.131442] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 3.140215] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 3.143482] systemd[1]: Mounting /sysroot... machine # [ 3.198153] EXT4-fs (vda): mounted filesystem 95ddb635-9197-408d-9497-a2308c5f2133 r/w with ordered data mode. Quota mode: none. machine # [ 3.187642] systemd[1]: Mounted /sysroot. machine # [ 3.191668] systemd[1]: Reached target Initrd Root File System. machine # [ 3.194221] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 3.229702] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 3.234635] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 3.240722] systemd[1]: Reloading... machine # [ 3.454798] systemd[1]: Reloading finished in 217 ms. machine # [ 3.489329] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace... machine # [ 3.494299] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace... machine # [ 3.500823] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.520822] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 3.536299] systemd[1]: Mounting /sysroot/run... machine # [ 3.560213] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.604302] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.636101] fuse: init (API version 7.45) machine # [ 3.622733] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 3.659263] virtiofs virtio6: discovered new tag: nix-store machine # [ 3.660139] virtiofs virtio6: virtio_fs_setup_dax: No cache capability machine # [ 3.675027] virtiofs virtio7: discovered new tag: shared machine # [ 3.675926] virtiofs virtio7: virtio_fs_setup_dax: No cache capability machine # [ 3.682798] virtiofs virtio8: discovered new tag: xchg machine # [ 3.683636] virtiofs virtio8: virtio_fs_setup_dax: No cache capability machine # [ 3.674555] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 3.678446] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 3.685502] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.705823] systemd[1]: Mounted /sysroot/run. machine # [ 3.710862] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.713976] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.725807] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.736328] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.769118] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.770749] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.490726] (udev-worker)[89]: mtd0ro: Failed to find and pin callout binary "/nix/store/fng2bb9zpmbrj4khaym6439can623sc3-systemd-263-889bc48/lib/udev/mtd_probe": No such file or directory machine # [ 4.496391] systemd[1]: Mounting /sysroot/nix/store... machine # [ 4.501847] (udev-worker)[89]: 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.560855] systemd[1]: Mounted /sysroot/nix/store. machine # [ 4.563376] systemd[1]: Reached target Initrd File Systems. machine # [ 4.564493] systemd[1]: Starting Find NixOS closure... machine # [ 4.578248] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 4.611743] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.615242] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.618179] systemd[1]: Stopping Virtual Console Setup... machine # [ 4.634921] systemd[1]: Starting Virtual Console Setup... machine # [ 4.637544] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 4.641479] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. machine # [ 4.647183] systemd[1]: Finished Find NixOS closure. machine # [ 4.663714] systemd[1]: Reached target Initrd Default Target. machine # [ 4.672789] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 4.691319] systemd-vconsole-setup[157]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 4.701568] systemd[1]: Finished Virtual Console Setup. machine # [ 4.713838] systemd[1]: Stopped target Initrd Default Target. machine # [ 4.715461] systemd[1]: Stopped target Basic System. machine # [ 4.720665] systemd[1]: Stopped target Initrd Root Device. machine # [ 4.721802] systemd[1]: Stopped target Path Units. machine # [ 4.722545] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 4.723823] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 4.728289] systemd[1]: Stopped target Slice Units. machine # [ 4.729262] systemd[1]: Stopped target Socket Units. machine # [ 4.730489] systemd[1]: Stopped target System Initialization. machine # [ 4.732635] systemd[1]: Stopped target Swaps. machine # [ 4.736914] systemd[1]: Stopped target Timer Units. machine # [ 4.742083] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 4.744259] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 4.747366] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 4.756329] systemd[1]: Stopped Find NixOS closure. machine # [ 4.757379] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.759438] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 4.763106] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.764354] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 4.765680] systemd[1]: Stopped Load Kernel Modules. machine # [ 4.766613] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 4.767794] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 4.776311] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 4.781322] systemd[1]: Stopped Create System Files and Directories. machine # [ 4.785409] systemd[1]: Stopped target Local File Systems. machine # [ 4.787237] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 4.790417] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 4.792217] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 4.796250] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 4.797593] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.798663] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.800706] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 4.803322] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 4.804632] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 4.805793] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 4.806858] systemd[1]: systemd-udevd.service: Consumed 1.703s CPU time over 3.120s wall clock time, 43.5M memory peak. machine # [ 4.808497] systemd[1]: Starting Cleanup udev Database... machine # [ 4.809335] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 4.810450] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 4.811363] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 4.817874] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 4.819233] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 4.824722] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 4.825988] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 4.827083] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.861919] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 4.863764] systemd[1]: Finished Cleanup udev Database. machine # [ 4.866283] systemd[1]: Reached target Switch Root. machine # [ 4.869940] systemd[1]: Starting NixOS Activation... machine # [ 4.959100] initrd-nixos-activation-start[174]: booting system configuration /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test machine # [ 4.997485] initrd-nixos-activation-start[174]: running activation script... machine # [ 5.271767] initrd-nixos-activation-start[197]: setting up /etc... machine # [ 5.411254] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 5.414963] systemd[1]: Finished NixOS Activation. machine # [ 5.416590] systemd[1]: Starting Switch Root... machine # [ 5.443699] systemd[1]: Switching root. machine # [ 5.630654] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). machine # [ 5.791251] systemd[1]: systemd 263-889bc48 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.792194] systemd[1]: Detected virtualization qemu. machine # [ 5.793005] systemd[1]: Detected architecture arm64. machine # [ 5.802357] systemd[1]: Detected first boot. machine # [ 5.803756] systemd[1]: Initializing machine ID from random generator. machine # [ 6.073862] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 6.287597] systemd[1]: Applying preset policy. machine # [ 6.520627] systemd[1]: Populated /etc with preset unit settings. machine # [ 6.732708] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 6.736287] systemd[1]: Stopped initrd-switch-root.service. machine # [ 6.740706] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 6.745926] systemd[1]: Created slice Slice /system/getty. machine # [ 6.747201] systemd[1]: Created slice User and Session Slice. machine # [ 6.747650] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 6.748038] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 6.748347] systemd[1]: Expecting device /dev/hvc0... machine # [ 6.748622] systemd[1]: Expecting device /dev/ttyAMA0... machine # [ 6.748901] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 6.756300] systemd[1]: Stopped target initrd-fs.target. machine # [ 6.756822] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 6.760009] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 6.760483] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 6.763946] systemd[1]: Reached target Path Units. machine # [ 6.764491] systemd[1]: Reached target Remote File Systems. machine # [ 6.767698] systemd[1]: Reached target Slice Units. machine # [ 6.768201] systemd[1]: Reached target Swaps. machine # [ 6.772940] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 6.777528] systemd[1]: Listening on Process Core Dump Socket. machine # [ 6.779134] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 6.780587] systemd[1]: Listening on Factory Reset Management. machine # [ 6.784588] systemd[1]: Listening on Hostname Service Socket. machine # [ 6.789717] systemd[1]: Starting Journal Log Access Socket... machine # [ 6.792010] systemd[1]: Listening on Journal Audit Socket. machine # [ 6.796293] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 6.799792] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 6.804452] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.805043] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 6.812301] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 6.813029] systemd[1]: Listening on udev Varlink Socket. machine # [ 6.818279] systemd[1]: Mounting Huge Pages File System... machine # [ 6.823199] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 6.837618] systemd[1]: Mounting Kernel Debug File System... machine # [ 6.854543] systemd[1]: Mounting Kernel Trace File System... machine # [ 6.878564] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 6.879252] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 6.905833] systemd[1]: Mounting Kernel Configuration File System... machine # [ 6.908767] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 6.912874] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 6.916949] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 6.958446] systemd[1]: Mounting FUSE Control File System... machine # [ 6.965494] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 7.006370] systemd[1]: Starting Journal Service... machine # [ 7.023062] systemd[1]: Starting Load Kernel Modules... machine # [ 7.045676] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 7.058629] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 7.059256] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.096715] systemd[1]: Starting Coldplug All udev Devices... machine # [ 7.108015] systemd[1]: Listening on Journal Log Access Socket. machine # [ 7.108688] systemd[1]: Mounted Huge Pages File System. machine # [ 7.125806] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 7.126674] systemd[1]: Mounted Kernel Debug File System. machine # [ 7.127169] systemd[1]: Mounted Kernel Trace File System. machine # [ 7.133706] systemd-journald[268]: Collecting audit messages is enabled. machine # [ 7.146050] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 7.138652] systemd[1]: Queued start job for default target Multi-User System. machine # [ 7.145752] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 7.158095] systemd-modules-load[269]: Module 'atkbd' is built in machine # [ 7.159247] systemd-modules-load[269]: Module 'loop' is built in machine # [ 7.181876] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 7.182846] systemd[1]: Started Journal Service. machine # [ 7.189578] systemd[1]: Finished Load Kernel Modules. machine # [ 7.218751] systemd[1]: Mounted Kernel Configuration File System. machine # [ 7.253212] EXT4-fs (vda): re-mounted 95ddb635-9197-408d-9497-a2308c5f2133. machine # [ 7.245411] systemd-oomd[270]: No swap; memory pressure usage will be degraded machine # [ 7.258384] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 7.264258] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 7.282836] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 7.294993] systemd[1]: Starting Firewall... machine # [ 7.302428] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 7.303593] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 7.312483] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 7.326346] systemd[1]: Starting Apply Kernel Variables... machine # [ 7.327382] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.334861] systemd[1]: Mounted FUSE Control File System. machine # [ 7.421711] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 7.455010] systemd-journald[268]: Received client request to flush runtime journal. machine # [ 7.588692] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 7.601249] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 7.608428] systemd[1]: Reached target First Boot Complete. machine # [ 7.609413] systemd[1]: Finished Apply Kernel Variables. machine # [ 7.610293] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 7.652978] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 7.656110] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 7.662218] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 7.726036] systemd[1]: Mounting /run/wrappers... machine # [ 7.816971] systemd[1]: Mounted /run/wrappers. machine # [ 7.817947] systemd[1]: Reached target Local File Systems. machine # [ 7.828234] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 7.835375] systemd-udevd[300]: Using default interface naming scheme 'v261'. machine # [ 7.838172] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 7.839108] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 7.847900] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 7.866926] systemd[1]: Starting Create System Files and Directories... machine # [ 8.032999] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 8.097623] systemd[1]: Finished Create System Files and Directories. machine # [ 8.120636] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 8.136370] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 8.281164] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 8.291969] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 8.300563] systemd[1]: Starting Update is Completed... machine # [ 8.372885] systemd[1]: Finished Update is Completed. machine # [ 8.673626] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 8.683986] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 8.858101] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 8.868253] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 9.081174] systemd[1]: Finished Coldplug All udev Devices. machine # [ 9.082732] systemd[1]: Reached target System Initialization. machine # [ 9.090906] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 9.092624] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 9.093841] systemd[1]: Reached target Timer Units. machine # [ 9.094622] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 9.095602] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 9.108690] systemd[1]: Reached target Socket Units. machine # [ 9.109724] systemd[1]: Reached target Basic System. machine # [ 9.110521] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 9.111610] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 9.128949] systemd[1]: Starting Post-Boot Actions... machine # [ 9.137236] systemd[1]: Started Reset console on configuration changes. machine # [ 9.185665] systemd[1]: Starting resolvconf update... machine # [ 9.242124] systemd[1]: Starting D-Bus System Message Bus... machine # [ 9.256126] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 9.320673] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 9.336094] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 9.345424] nsncd[420]: Oct 01 03:46:20.720 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 9.379785] systemd[1]: Finished Post-Boot Actions. machine # [ 9.391275] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 9.400163] systemd[1]: Reached target User and Group Name Lookups. machine # [ 9.427110] systemd[1]: Starting User Login Management... machine # [ 9.458592] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 9.556685] dbus-broker-launch[427]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 9.564200] dbus-broker-launch[427]: NSS returned no entry for 'systemd-timesync' machine # [ 9.565609] dbus-broker-launch[427]: Invalid user-name in /nix/store/r21qlc763yx2sp1lnrvn2wkyx9l5bgx1-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 9.638082] systemd[1]: Started D-Bus System Message Bus. machine # [ 9.658889] systemd-logind[448]: New seat seat0. machine # [ 9.669323] systemd[1]: Started User Login Management. machine # [ 9.681628] systemd[1]: Starting linger-users.service... machine # [ 9.698173] dbus-broker-launch[427]: Ready machine # [ 9.799934] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 9.807277] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 9.813874] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 9.814911] systemd[1]: Stopping User and Group Name Lookups... machine # [ 9.815799] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 9.826944] systemd[1]: nscd.service: Deactivated successfully. machine # [ 9.827946] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 9.883774] systemd[1]: Created slice Slice /user/1001. machine # [ 9.895428] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 9.907801] systemd[1]: Starting User Runtime Directory /run/user/1001... machine # [ 10.007624] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 10.015815] systemd[1]: Finished linger-users.service. machine # [ 10.028776] systemd[1]: Finished resolvconf update. machine # [ 10.046744] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 10.056805] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 10.057947] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 10.059005] systemd[1]: Reached target User and Group Name Lookups. machine # [ 10.071806] nsncd[518]: Oct 01 03:46:21.438 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 10.082589] systemd[1]: Starting DHCP Client... machine # [ 10.137253] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. machine # [ 10.155415] systemd[1]: Started backdoor.service. machine # [ 10.182046] systemd[1]: Finished User Runtime Directory /run/user/1001. machine # [ 10.252175] systemd[1]: Starting User Manager for UID 1001... machine # [ 10.348887] (udev-worker)[407]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 10.395071] (udev-worker)[407]: Network interface NamePolicy= disabled on kernel command line. machine # [ 10.434578] (systemd)[556]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 10.472651] systemd-logind[448]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'. machine # [ 10.556846] systemd[556]: 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 12.75 seconds) machine: (finished: waiting for the VM to finish booting, in 13.19 seconds) machine # [ 10.884649] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 10.886509] systemd-logind[448]: Watching system buttons on /dev/input/event0 (gpio-keys) machine # [ 10.887729] systemd[556]: Queued start job for default target Main User Target. machine # [ 10.932855] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 10.934762] systemd[556]: Created slice User Application Slice. machine # [ 10.949613] systemd[556]: Started Daily Cleanup of User's Temporary Directories. machine # [ 10.981239] systemd[556]: Reached target Paths. machine # [ 10.982193] systemd[556]: Reached target Timers. machine # [ 10.982921] systemd[556]: Starting D-Bus User Message Bus Socket... machine # [ 10.983845] systemd[556]: Starting Create User Files and Directories... machine # [ 11.004810] systemd[556]: Finished Create User Files and Directories. machine # [ 11.006036] systemd[556]: Listening on D-Bus User Message Bus Socket. machine # [ 11.027620] systemd[556]: Reached target Sockets. machine # [ 11.038790] systemd[556]: Reached target Basic System. machine # [ 11.039765] systemd[1]: Started User Manager for UID 1001. machine # [ 11.047344] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 11.053300] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 11.054372] systemd[556]: Reached target Main User Target. machine # [ 11.055210] systemd[556]: Startup finished in 516ms. machine # [ 11.067699] dhcpcd[560]: dhcpcd-10.3.2 starting machine # [ 11.086806] dhcpcd[597]: dev: loaded udev machine # [ 11.145765] 8021q: 802.1Q VLAN Support v1.8 machine # [ 11.269958] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 11.309500] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 11.310040] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 11.312085] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 11.312428] cfg80211: failed to load regulatory.db machine # [ 11.401304] mousedev: PS/2 mouse device common for all mice machine # [ 11.424588] systemd[1]: Finished Firewall. machine # [ 11.425435] systemd[1]: Reached target Preparation for Network. machine # [ 11.444862] systemd[1]: Starting Address configuration of eth1... machine # [ 11.446079] systemd[1]: Starting Extra networking commands.... machine # [ 11.468318] systemd-logind[448]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 11.679199] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 11.678561] dhcpcd[597]: eth0: waiting for carrier machine # [ 11.679455] dhcpcd[597]: eth0: carrier acquired machine # [ 11.768742] dhcpcd[597]: DUID 00:01:00:01:32:50:94:0f:52:54:00:12:34:56 machine # [ 11.769906] dhcpcd[597]: eth0: IAID 00:12:34:56 machine # [ 11.770609] dhcpcd[597]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 11.805898] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 11.890545] network-addresses-eth1-start[625]: adding address 192.168.1.1/24... done machine # [ 11.976844] network-addresses-eth1-start[625]: adding address 2001:db8:1::1/64... done machine # [ 12.107625] systemd[1]: Finished Address configuration of eth1. machine # [ 12.536826] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 machine # [ 12.825973] systemd[1]: Finished Extra networking commands.. machine # [ 12.826928] systemd[1]: Reached target Network. machine # [ 12.847449] systemd[1]: Starting Permit User Sessions... machine # [ 12.944513] dhcpcd[597]: eth0: soliciting a DHCP lease machine # [ 12.962186] dhcpcd[597]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 12.963198] dhcpcd[597]: eth0: probing address 10.0.2.15/24 machine # [ 13.024497] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 13.051355] systemd[1]: Starting Virtual Console Setup... machine # [ 13.103911] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 13.120274] systemd[1]: Stopped Virtual Console Setup. machine # [ 13.121289] systemd[1]: Starting Virtual Console Setup... machine # [ 13.136736] systemd[1]: Finished Permit User Sessions. machine # [ 13.137693] systemd[1]: Started Getty on tty1. machine # [ 13.138355] systemd[1]: Reached target Login Prompts. machine # [ 13.139408] systemd-logind[448]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 13.255243] systemd-vconsole-setup[705]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 13.261713] systemd[1]: Finished Virtual Console Setup. machine # [ 13.481998] dhcpcd[597]: eth0: soliciting an IPv6 router machine # [ 13.484451] dhcpcd[597]: eth0: Router Advertisement from fe80::2 machine # [ 13.486137] dhcpcd[597]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 13.487657] dhcpcd[597]: eth0: adding route to fec0::/64 machine # [ 13.488975] dhcpcd[597]: eth0: adding default route via fe80::2 machine # [ 18.273880] dhcpcd[597]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 18.274158] dhcpcd[597]: eth0: adding route to 10.0.2.0/24 machine # [ 18.274241] dhcpcd[597]: eth0: adding default route via 10.0.2.2 machine # [ 18.395208] systemd[1]: Started DHCP Client. machine # [ 18.397770] systemd[1]: Reached target Multi-User System. machine # [ 18.398719] systemd[1]: Startup finished in 1.193s (kernel) + 4.497s (initrd) + 12.706s (userspace) = 18.397s. machine: (finished: waiting for unit multi-user.target, in 20.84 seconds) machine: must succeed: /nix/store/vyagcp08nk4jxixq8lbwf1aba9f9a1qj-stderr-runner /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/bin/switch-to-configuration test machine # Checking switch inhibitors... done machine # [ 18.737713] nixos[815]: switching to system configuration /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test machine # [ 18.740393] systemd[1]: Stopped target Local File Systems. machine # activating the configuration... machine # [ 18.743243] systemd[1]: Stopped target Remote File Systems. machine # setting up /etc... machine # [ 19.049740] systemd[1]: Reload requested from client PID 815 ('.switch-to-conf') (unit backdoor.service)... machine # [ 19.051408] systemd[1]: Reloading... machine # [ 19.404800] systemd[1]: Reloading finished in 351 ms. machine # reloading user units for usertest... machine # [ 19.458226] systemd[556]: Created slice User Core Session Slice. machine # [ 19.461747] systemd[556]: Starting D-Bus User Message Bus... machine # [ 19.497064] dbus-broker-launch[885]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/r21qlc763yx2sp1lnrvn2wkyx9l5bgx1-system-path/share/dbus-1/services/org.freedesktop.import1.service' machine # [ 19.500203] dbus-broker-launch[885]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/r21qlc763yx2sp1lnrvn2wkyx9l5bgx1-system-path/share/dbus-1/services/org.freedesktop.machine1.service' machine # [ 19.503232] dbus-broker-launch[885]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/r21qlc763yx2sp1lnrvn2wkyx9l5bgx1-system-path/share/dbus-1/services/org.freedesktop.portable1.service' machine # [ 19.506427] dbus-broker-launch[885]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/r21qlc763yx2sp1lnrvn2wkyx9l5bgx1-system-path/share/dbus-1/services/org.freedesktop.systemd1.service' machine # [ 19.511068] dbus-broker-launch[885]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored machine # [ 19.514740] dbus-broker-launch[885]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored machine # [ 19.516970] systemd[556]: Started D-Bus User Message Bus. machine # [ 19.519687] dbus-broker-launch[885]: Ready machine # [ 19.563509] systemd[556]: Reexecution requested from client PID 884 ('switch-to-confi')... machine # [ 19.565917] systemd[556]: Reexecuting. machine # [ 19.623966] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 19.738887] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 19.745722] systemd[556]: Reload requested from client PID 884 ('switch-to-confi')... machine # [ 19.748220] systemd[556]: Reloading... machine # [ 19.856740] systemd[556]: Reloading finished in 107 ms. machine # [ 19.872212] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 19.883779] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 19.886387] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 19.888756] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 19.922839] systemd[556]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 19.934985] systemd[1]: Starting Re-setup tmpfiles on a system that is already running.... machine # [ 20.024446] systemd[1]: Finished Re-setup tmpfiles on a system that is already running.. machine # [ 20.028416] systemd[1]: Reached target Reactivate sysinit units. machine # [ 20.030412] systemd[1]: Reached target Remote File Systems. machine # [ 20.038009] systemd[1]: Starting linger-users.service... machine # [ 20.049406] systemd[1]: Reached target Local File Systems. machine # [ 20.052109] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 20.062091] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 20.063087] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 20.073131] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 20.075440] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 20.083708] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 20.087360] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 20.092711] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 20.094206] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 20.136083] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 20.140590] systemd[1]: Finished linger-users.service. machine # [ 20.289516] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 20.293289] systemd[1]: Finished Create SUID/SGID Wrappers. machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service machine # [ 20.550502] nixos[815]: finished switching to system configuration /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test machine: (finished: must succeed: /nix/store/vyagcp08nk4jxixq8lbwf1aba9f9a1qj-stderr-runner /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/bin/switch-to-configuration test, in 2.04 seconds) machine: must succeed: rm /etc/NIXOS machine: (finished: must succeed: rm /etc/NIXOS, in 0.03 seconds) machine: must succeed: /nix/store/vyagcp08nk4jxixq8lbwf1aba9f9a1qj-stderr-runner /nix/store/lhyz1cqawdrp86fih2gasi7wyafp6sqs-nixos-system-other-test/bin/switch-to-configuration test machine # this should succeed (config: /nix/store/lhyz1cqawdrp86fih2gasi7wyafp6sqs-nixos-system-other-test, action: test) machine # Checking switch inhibitors... done machine # [ 20.813617] nixos[962]: switching to system configuration /nix/store/lhyz1cqawdrp86fih2gasi7wyafp6sqs-nixos-system-other-test machine # [ 20.816737] systemd[1]: Stopped target Local File Systems. machine # activating the configuration... machine # [ 20.819787] systemd[1]: Stopped target Remote File Systems. machine # removing user ‘usertest’ machine # setting up /etc... machine # [ 21.112372] systemd[1]: Reload requested from client PID 962 ('.switch-to-conf') (unit backdoor.service)... machine # [ 21.114361] systemd[1]: Reloading... machine # [ 21.470499] systemd[1]: Reloading finished in 354 ms. machine # reloading user units for usertest... machine # [ 21.584461] systemd[556]: Reexecution requested from client PID 1033 ('switch-to-confi')... machine # [ 21.585948] systemd[556]: Reexecuting. machine # [ 21.639749] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 21.755034] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 21.761715] systemd[556]: Reload requested from client PID 1033 ('switch-to-confi')... machine # [ 21.765594] systemd[556]: Reloading... machine # [ 21.870754] systemd[556]: Reloading finished in 106 ms. machine # [ 21.889634] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 21.898972] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 21.901333] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 21.903485] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 21.936117] systemd[556]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 21.940863] systemd[1]: Stopped target Reactivate sysinit units. machine # reloading the following units: dbus-broker.service machine # [ 21.945374] systemd[1]: Stopping Reactivate sysinit units... machine # [ 21.946241] systemd[1]: Reached target Reactivate sysinit units. machine # [ 21.948482] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 21.949811] dbus-broker-launch[427]: Caught SIGHUP, trigger reload. machine # [ 21.956400] dbus-broker-launch[427]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 21.958546] dbus-broker-launch[427]: NSS returned no entry for 'systemd-timesync' machine # [ 21.959599] dbus-broker-launch[427]: Invalid user-name in /nix/store/bazmz3pkgsxs31n9mf1a0bzwvrdy5sq6-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 21.984969] systemd[1]: Reloaded D-Bus System Message Bus. machine # restarting the following units: network-addresses-eth1.service machine # [ 21.991690] systemd[1]: Stopping Address configuration of eth1... machine # [ 22.023347] network-addresses-eth1-pre-stop[1046]: deleting address 192.168.1.1/24... done machine # [ 22.029814] network-addresses-eth1-pre-stop[1046]: deleting address 2001:db8:1::1/64... done machine # [ 22.036567] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 22.040293] systemd[1]: Stopped Address configuration of eth1. machine # [ 22.043550] systemd[1]: Starting Address configuration of eth1... machine # [ 22.086362] network-addresses-eth1-start[1051]: adding address 192.168.1.2/24... done machine # [ 22.093351] network-addresses-eth1-start[1051]: adding address 2001:db8:1::2/64... done machine # [ 22.103125] systemd[1]: Finished Address configuration of eth1. machine # [ 22.109542] systemd[1]: Reached target Remote File Systems. machine # [ 22.112392] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.113788] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 22.119699] systemd[1]: Starting linger-users.service... machine # [ 22.124988] systemd[1]: Reached target Local File Systems. machine # [ 22.134616] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 22.142078] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 22.142977] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 22.147751] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 22.150370] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 22.151945] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.156322] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.189347] linger-users-start[1065]: id: ‘usertest’: no such user machine # [ 22.191920] linger-users-start[1062]: Removing linger for missing user usertest machine # [ 22.201729] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 22.203736] systemd[1]: Finished linger-users.service. machine # [ 22.366071] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 22.368788] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 22.624581] nixos[962]: finished switching to system configuration /nix/store/lhyz1cqawdrp86fih2gasi7wyafp6sqs-nixos-system-other-test machine: (finished: must succeed: /nix/store/vyagcp08nk4jxixq8lbwf1aba9f9a1qj-stderr-runner /nix/store/lhyz1cqawdrp86fih2gasi7wyafp6sqs-nixos-system-other-test/bin/switch-to-configuration test, in 2.05 seconds) machine: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 22.836156] nixos[1129]: switching to system configuration /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test machine # [ 22.838677] systemd[1]: Stopped target Local File Systems. machine # [ 22.840971] systemd[1]: Stopped target Remote File Systems. machine # [ 23.120543] systemd[1]: Reload requested from client PID 1129 ('.switch-to-conf') (unit backdoor.service)... machine # [ 23.122201] systemd[1]: Reloading... machine # [ 23.441280] systemd[1]: Reloading finished in 317 ms. machine # [ 23.526021] systemd[556]: Reexecution requested from client PID 1198 ('switch-to-confi')... machine # [ 23.530038] systemd[556]: Reexecuting. machine # [ 23.587456] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.701932] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.708683] systemd[556]: Reload requested from client PID 1198 ('switch-to-confi')... machine # [ 23.711344] systemd[556]: Reloading... machine # [ 23.817798] systemd[556]: Reloading finished in 105 ms. machine # [ 23.832206] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.843299] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 23.844477] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 23.847058] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 23.880580] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 23.884565] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 23.889554] systemd[1]: Stopping Reactivate sysinit units... machine # [ 23.890612] systemd[1]: Reached target Reactivate sysinit units. machine # [ 23.891459] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 23.893200] dbus-broker-launch[427]: Caught SIGHUP, trigger reload. machine # [ 23.896792] dbus-broker-launch[427]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 23.898906] dbus-broker-launch[427]: NSS returned no entry for 'systemd-timesync' machine # [ 23.899971] dbus-broker-launch[427]: Invalid user-name in /nix/store/r21qlc763yx2sp1lnrvn2wkyx9l5bgx1-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 23.924341] systemd[1]: Reloaded D-Bus System Message Bus. machine # [ 23.930767] systemd[1]: Stopping Address configuration of eth1... machine # [ 23.962825] network-addresses-eth1-pre-stop[1210]: deleting address 192.168.1.2/24... done machine # [ 23.971065] network-addresses-eth1-pre-stop[1210]: deleting address 2001:db8:1::2/64... done machine # [ 23.977993] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 23.980187] systemd[1]: Stopped Address configuration of eth1. machine # [ 23.984617] systemd[1]: Starting Address configuration of eth1... machine # [ 24.026293] network-addresses-eth1-start[1215]: adding address 192.168.1.1/24... done machine # [ 24.033757] network-addresses-eth1-start[1215]: adding address 2001:db8:1::1/64... done machine # [ 24.043116] systemd[1]: Finished Address configuration of eth1. machine # [ 24.047958] systemd[1]: Reached target Remote File Systems. machine # [ 24.053729] systemd[1]: Starting linger-users.service... machine # [ 24.062052] systemd[1]: Reached target Local File Systems. machine # [ 24.066852] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.075855] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 24.085259] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 24.092074] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 24.095662] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 24.097956] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 24.103132] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 24.106927] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.111371] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.134932] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 24.137962] systemd[1]: Finished linger-users.service. machine # [ 24.282942] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 24.286266] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 24.540270] nixos[1129]: finished switching to system configuration /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.92 seconds) subtest: user services machine: waiting for unit user@1001.service machine: (finished: waiting for unit user@1001.service, in 0.09 seconds) machine: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 24.838996] nixos[1288]: switching to system configuration /nix/store/vz3p3qghngmbhhz2rlgx0vrpbfcnl27b-nixos-system-machine-test machine # [ 24.841831] systemd[1]: Stopped target Remote File Systems. machine # [ 24.844181] systemd[1]: Stopped target Local File Systems. machine # [ 25.148574] systemd[1]: Reload requested from client PID 1288 ('.switch-to-conf') (unit backdoor.service)... machine # [ 25.150358] systemd[1]: Reloading... machine # [ 25.465568] systemd[1]: Reloading finished in 313 ms. machine # [ 25.556538] systemd[556]: Reexecution requested from client PID 1358 ('switch-to-confi')... machine # [ 25.559019] systemd[556]: Reexecuting. machine # [ 25.619573] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 25.738104] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 25.744491] systemd[556]: Reload requested from client PID 1358 ('switch-to-confi')... machine # [ 25.747123] systemd[556]: Reloading... machine # [ 25.855669] systemd[556]: Reloading finished in 107 ms. machine # [ 25.872226] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 25.882896] systemd[556]: Starting usertest.service... machine # [ 25.910168] systemd[556]: Finished usertest.service. machine # [ 25.914220] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 25.915261] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 25.917842] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 25.947620] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 25.951811] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 25.954526] systemd[1]: Stopping Reactivate sysinit units... machine # [ 25.955384] systemd[1]: Reached target Reactivate sysinit units. machine # [ 25.960407] systemd[1]: Reached target Remote File Systems. machine # [ 25.962776] systemd[1]: Starting linger-users.service... machine # [ 25.974421] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 25.975866] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 25.979389] systemd[1]: Reached target Local File Systems. machine # [ 25.991298] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 25.997405] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 26.002216] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 26.003576] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 26.010982] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 26.015689] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.020263] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 26.056654] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 26.059505] systemd[1]: Finished linger-users.service. machine # [ 26.197206] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 26.200574] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 26.454646] nixos[1288]: finished switching to system configuration /nix/store/vz3p3qghngmbhhz2rlgx0vrpbfcnl27b-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-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 # [ 26.502014] sudo[1428]: 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 # [ 26.517084] sudo[1428]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 26.530689] sudo[1428]: 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/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 26.709837] nixos[1437]: switching to system configuration /nix/store/vz3p3qghngmbhhz2rlgx0vrpbfcnl27b-nixos-system-machine-test machine # [ 26.712386] systemd[1]: Stopped target Local File Systems. machine # [ 26.715074] systemd[1]: Stopped target Remote File Systems. machine # [ 26.982321] systemd[1]: Reload requested from client PID 1437 ('.switch-to-conf') (unit backdoor.service)... machine # [ 26.984521] systemd[1]: Reloading... machine # [ 27.293601] systemd[1]: Reloading finished in 306 ms. machine # [ 27.376158] systemd[556]: Reexecution requested from client PID 1506 ('switch-to-confi')... machine # [ 27.379957] systemd[556]: Reexecuting. machine # [ 27.439342] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.554405] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.561147] systemd[556]: Reload requested from client PID 1506 ('switch-to-confi')... machine # [ 27.564085] systemd[556]: Reloading... machine # [ 27.672433] systemd[556]: Reloading finished in 108 ms. machine # [ 27.688174] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.697479] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 27.699788] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 27.702087] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 27.737266] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 27.741466] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 27.742421] systemd[1]: Stopping Reactivate sysinit units... machine # [ 27.743210] systemd[1]: Reached target Reactivate sysinit units. machine # [ 27.746489] systemd[1]: Reached target Local File Systems. machine # [ 27.750352] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 27.753974] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 27.754899] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 27.764852] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 27.767259] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 27.776297] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.777664] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.778870] systemd[1]: Reached target Remote File Systems. machine # [ 27.779664] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.782123] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 27.973852] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 27.978790] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 27.981233] systemd[1]: Starting linger-users.service... machine # [ 28.022485] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 28.025849] systemd[1]: Finished linger-users.service. machine # [ 28.281504] nixos[1437]: finished switching to system configuration /nix/store/vz3p3qghngmbhhz2rlgx0vrpbfcnl27b-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.75 seconds) machine: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1 machine # [ 28.484205] nixos[1578]: switching to system configuration /nix/store/dw5sx891mqdfpg8v28yg2jcabzax7pq2-nixos-system-machine-test machine # [ 28.486998] systemd[1]: Stopped target Local File Systems. machine # [ 28.490007] systemd[1]: Stopped target Remote File Systems. machine # [ 28.772313] systemd[1]: Reload requested from client PID 1578 ('.switch-to-conf') (unit backdoor.service)... machine # [ 28.774382] systemd[1]: Reloading... machine # [ 29.085375] systemd[1]: Reloading finished in 309 ms. machine # [ 29.175730] systemd[556]: Stopped usertest.service. machine # [ 29.179038] systemd[556]: Reexecution requested from client PID 1647 ('switch-to-confi')... machine # [ 29.181936] systemd[556]: Reexecuting. machine # [ 29.243491] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.361729] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.368142] systemd[556]: Reload requested from client PID 1647 ('switch-to-confi')... machine # [ 29.370638] systemd[556]: Reloading... machine # [ 29.477703] systemd[556]: Reloading finished in 106 ms. machine # [ 29.492175] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.502203] systemd[556]: Starting usertest.service... machine # [ 29.524734] systemd[556]: Finished usertest.service. machine # [ 29.527723] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 29.530077] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 29.532533] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 29.563719] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 29.568800] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 29.569766] systemd[1]: Stopping Reactivate sysinit units... machine # [ 29.570570] systemd[1]: Reached target Reactivate sysinit units. machine # [ 29.574712] systemd[1]: Reached target Remote File Systems. machine # [ 29.578994] systemd[1]: Starting linger-users.service... machine # [ 29.587502] systemd[1]: Reached target Local File Systems. machine # [ 29.592179] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 29.593751] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 29.594573] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 29.595841] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 29.611163] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 29.621189] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 29.622614] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 29.631236] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 29.637849] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 29.674858] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 29.676635] systemd[1]: Finished linger-users.service. machine # [ 29.803462] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 29.806647] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 30.062266] nixos[1578]: finished switching to system configuration /nix/store/dw5sx891mqdfpg8v28yg2jcabzax7pq2-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 1.78 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 30.105287] sudo[1717]: 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.120762] sudo[1717]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 30.134253] sudo[1717]: 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/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 30.315229] nixos[1726]: switching to system configuration /nix/store/wn213nirbsb8klnhagpwlni8glld8ysn-nixos-system-machine-test machine # [ 30.319612] systemd[1]: Stopped target Remote File Systems. machine # [ 30.321467] systemd[1]: Stopped target Local File Systems. machine # [ 30.599096] systemd[1]: Reload requested from client PID 1726 ('.switch-to-conf') (unit backdoor.service)... machine # [ 30.601301] systemd[1]: Reloading... machine # [ 30.903207] systemd[1]: Reloading finished in 300 ms. machine # [ 30.996454] systemd[556]: Reexecution requested from client PID 1795 ('switch-to-confi')... machine # [ 30.997864] systemd[556]: Reexecuting. machine # [ 31.055395] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 31.170883] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 31.177373] systemd[556]: Reload requested from client PID 1795 ('switch-to-confi')... machine # [ 31.179540] systemd[556]: Reloading... machine # [ 31.332004] systemd[556]: Reloading finished in 151 ms. machine # [ 31.348166] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 31.356072] systemd[556]: Stopped usertest.service. machine # [ 31.357005] systemd[556]: Stopping usertest.service... machine # [ 31.359392] systemd[556]: Starting usertest.service... machine # [ 31.384963] systemd[556]: Finished usertest.service. machine # [ 31.390123] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 31.392139] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 31.394380] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 31.426850] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 31.430730] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 31.433626] systemd[1]: Stopping Reactivate sysinit units... machine # [ 31.434477] systemd[1]: Reached target Reactivate sysinit units. machine # [ 31.439414] systemd[1]: Reached target Remote File Systems. machine # [ 31.443727] systemd[1]: Starting linger-users.service... machine # [ 31.452309] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 31.453677] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 31.468288] systemd[1]: Reached target Local File Systems. machine # [ 31.470469] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 31.471963] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 31.473004] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 31.474241] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 31.479248] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 31.484150] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 31.491247] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 31.530552] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 31.534674] systemd[1]: Finished linger-users.service. machine # [ 31.662758] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 31.666083] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 31.920088] nixos[1726]: finished switching to system configuration /nix/store/wn213nirbsb8klnhagpwlni8glld8ysn-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/simpleUserServiceNostop/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 # [ 31.962451] sudo[1866]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 31.980577] sudo[1866]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 31.994383] sudo[1866]: 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/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1 machine # [ 32.179789] nixos[1875]: switching to system configuration /nix/store/y5g68zc5an69gw1val7yq2mzip7l1pg6-nixos-system-machine-test machine # [ 32.182497] systemd[1]: Stopped target Remote File Systems. machine # [ 32.184837] systemd[1]: Stopped target Local File Systems. machine # [ 32.467657] systemd[1]: Reload requested from client PID 1875 ('.switch-to-conf') (unit backdoor.service)... machine # [ 32.470011] systemd[1]: Reloading... machine # [ 32.776125] systemd[1]: Reloading finished in 304 ms. machine # [ 32.866845] systemd[556]: Reexecution requested from client PID 1944 ('switch-to-confi')... machine # [ 32.869773] systemd[556]: Reexecuting. machine # [ 32.923381] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.042015] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.049073] systemd[556]: Reload requested from client PID 1944 ('switch-to-confi')... machine # [ 33.052164] systemd[556]: Reloading... machine # [ 33.164538] systemd[556]: Reloading finished in 112 ms. machine # [ 33.176163] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.185185] systemd[556]: Reloading usertest.service... machine # [ 33.214488] systemd[556]: Reloaded usertest.service. machine # [ 33.219819] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 33.222426] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 33.224783] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 33.256949] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 33.261382] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 33.262363] systemd[1]: Stopping Reactivate sysinit units... machine # [ 33.263162] systemd[1]: Reached target Reactivate sysinit units. machine # [ 33.266090] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.270149] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 33.274756] systemd[1]: Reached target Local File Systems. machine # [ 33.275648] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 33.288643] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 33.289542] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 33.290791] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 33.297588] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 33.299207] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.300518] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.301820] systemd[1]: Reached target Remote File Systems. machine # [ 33.454792] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 33.457605] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 33.461865] systemd[1]: Starting linger-users.service... machine # [ 33.500357] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 33.503435] systemd[1]: Finished linger-users.service. machine # [ 33.757487] nixos[1875]: finished switching to system configuration /nix/store/y5g68zc5an69gw1val7yq2mzip7l1pg6-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/simpleUserServiceReload/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 # [ 33.799569] sudo[2014]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 33.814711] sudo[2014]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 33.828729] sudo[2014]: 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/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 34.009194] nixos[2023]: switching to system configuration /nix/store/vz3p3qghngmbhhz2rlgx0vrpbfcnl27b-nixos-system-machine-test machine # [ 34.011940] systemd[1]: Stopped target Remote File Systems. machine # [ 34.014323] systemd[1]: Stopped target Local File Systems. machine # [ 34.273632] systemd[1]: Reload requested from client PID 2023 ('.switch-to-conf') (unit backdoor.service)... machine # [ 34.275559] systemd[1]: Reloading... machine # [ 34.587352] systemd[1]: Reloading finished in 310 ms. machine # [ 34.711261] systemd[556]: Stopped usertest.service. machine # [ 34.715042] systemd[556]: Reexecution requested from client PID 2092 ('switch-to-confi')... machine # [ 34.717199] systemd[556]: Reexecuting. machine # [ 34.779517] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.897303] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 34.904002] systemd[556]: Reload requested from client PID 2092 ('switch-to-confi')... machine # [ 34.907455] systemd[556]: Reloading... machine # [ 35.011912] systemd[556]: Reloading finished in 105 ms. machine # [ 35.028177] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.038621] systemd[556]: Starting usertest.service... machine # [ 35.064570] systemd[556]: Finished usertest.service. machine # [ 35.067092] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 35.069370] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 35.071468] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 35.102326] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 35.106490] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 35.112199] systemd[1]: Stopping Reactivate sysinit units... machine # [ 35.113120] systemd[1]: Reached target Reactivate sysinit units. machine # [ 35.113969] systemd[1]: Reached target Local File Systems. machine # [ 35.114731] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 35.118746] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 35.119643] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 35.129239] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 35.131452] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 35.138061] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 35.139380] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 35.142271] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 35.143578] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 35.146192] systemd[1]: Reached target Remote File Systems. machine # [ 35.291318] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 35.294311] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 35.298465] systemd[1]: Starting linger-users.service... machine # [ 35.339754] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 35.341855] systemd[1]: Finished linger-users.service. machine # [ 35.597763] nixos[2023]: finished switching to system configuration /nix/store/vz3p3qghngmbhhz2rlgx0vrpbfcnl27b-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-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 # [ 35.641198] sudo[2163]: 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 # [ 35.656857] sudo[2163]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 35.670002] sudo[2163]: 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/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1 machine # [ 35.849451] nixos[2172]: switching to system configuration /nix/store/x3aba4hzqcdsqc8d05n5w3vkkwcnjdrc-nixos-system-machine-test machine # [ 35.851955] systemd[1]: Stopped target Local File Systems. machine # [ 35.853900] systemd[1]: Stopped target Remote File Systems. machine # [ 36.130614] systemd[1]: Reload requested from client PID 2172 ('.switch-to-conf') (unit backdoor.service)... machine # [ 36.132624] systemd[1]: Reloading... machine # [ 36.578805] systemd[1]: Reloading finished in 444 ms. machine # [ 36.672266] systemd[556]: Reexecution requested from client PID 2241 ('switch-to-confi')... machine # [ 36.673730] systemd[556]: Reexecuting. machine # [ 36.723029] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.835349] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.842297] systemd[556]: Reload requested from client PID 2241 ('switch-to-confi')... machine # [ 36.844695] systemd[556]: Reloading... machine # [ 36.952567] systemd[556]: Reloading finished in 106 ms. machine # [ 36.970750] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 36.978963] systemd[556]: Reloading usertest.service... machine # [ 37.005094] systemd[556]: Reloaded usertest.service. machine # [ 37.010368] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 37.012693] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 37.014743] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 37.046640] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 37.050466] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 37.052543] systemd[1]: Stopping Reactivate sysinit units... machine # [ 37.053478] systemd[1]: Reached target Reactivate sysinit units. machine # [ 37.056600] systemd[1]: Reached target Local File Systems. machine # [ 37.057460] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 37.064554] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 37.065476] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 37.066720] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 37.079891] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 37.085005] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.086324] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.087523] systemd[1]: Reached target Remote File Systems. machine # [ 37.088413] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.089710] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 37.241371] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 37.244821] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 37.247579] systemd[1]: Starting linger-users.service... machine # [ 37.288586] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 37.291718] systemd[1]: Finished linger-users.service. machine # [ 37.545590] nixos[2172]: finished switching to system configuration /nix/store/x3aba4hzqcdsqc8d05n5w3vkkwcnjdrc-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 1.88 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 37.587744] sudo[2311]: 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 # [ 37.603622] sudo[2311]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 37.618365] sudo[2311]: 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/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1 machine # [ 37.805222] nixos[2320]: switching to system configuration /nix/store/jzn3srbd27x9cw5ncd6sajla437gq257-nixos-system-machine-test machine # [ 37.807706] systemd[1]: Stopped target Remote File Systems. machine # [ 37.809688] systemd[1]: Stopped target Local File Systems. machine # [ 38.134118] systemd[1]: Reload requested from client PID 2320 ('.switch-to-conf') (unit backdoor.service)... machine # [ 38.136332] systemd[1]: Reloading... machine # [ 38.436532] systemd[1]: Reloading finished in 298 ms. machine # [ 38.526090] systemd[556]: Stopped usertest.service. machine # [ 38.529633] systemd[556]: Reexecution requested from client PID 2389 ('switch-to-confi')... machine # [ 38.531041] systemd[556]: Reexecuting. machine # [ 38.583288] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 38.693497] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 38.700524] systemd[556]: Reload requested from client PID 2389 ('switch-to-confi')... machine # [ 38.704085] systemd[556]: Reloading... machine # [ 38.807478] systemd[556]: Reloading finished in 104 ms. machine # [ 38.824182] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 38.835096] systemd[556]: Starting usertest.service... machine # [ 38.860500] systemd[556]: usertest.service: Main process exited, code=exited, status=1/FAILURE machine # [ 38.863618] systemd[556]: usertest.service: Failed with result 'exit-code'. machine # [ 38.867820] systemd[556]: Failed to start usertest.service. machine # [ 38.870641] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 38.871586] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 38.876196] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 38.903044] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 38.906897] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 38.909441] systemd[1]: Stopping Reactivate sysinit units... machine # [ 38.910288] systemd[1]: Reached target Reactivate sysinit units. machine # [ 38.914549] systemd[1]: Reached target Local File Systems. machine # [ 38.915436] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 38.924483] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 38.925380] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 38.926616] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 38.934832] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 38.942597] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 38.943941] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 38.947746] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 38.951624] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 38.953356] systemd[1]: Reached target Remote File Systems. machine # [ 39.100433] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 39.102294] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 39.106879] systemd[1]: Starting linger-users.service... machine # [ 39.149533] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 39.152800] systemd[1]: Finished linger-users.service. machine # [ 39.406828] nixos[2320]: switching to system configuration /nix/store/jzn3srbd27x9cw5ncd6sajla437gq257-nixos-system-machine-test failed (status 4) machine: (finished: must fail: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 1.79 seconds) machine: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 39.615479] nixos[2463]: switching to system configuration /nix/store/vz3p3qghngmbhhz2rlgx0vrpbfcnl27b-nixos-system-machine-test machine # [ 39.621134] systemd[1]: Stopped target Local File Systems. machine # [ 39.622034] systemd[1]: Stopped target Remote File Systems. machine # [ 39.880689] systemd[1]: Reload requested from client PID 2463 ('.switch-to-conf') (unit backdoor.service)... machine # [ 39.882925] systemd[1]: Reloading... machine # [ 40.193681] systemd[1]: Reloading finished in 308 ms. machine # [ 40.276603] systemd[556]: Reexecution requested from client PID 2531 ('switch-to-confi')... machine # [ 40.279681] systemd[556]: Reexecuting. machine # [ 40.335270] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 40.447551] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 40.454670] systemd[556]: Reload requested from client PID 2531 ('switch-to-confi')... machine # [ 40.455956] systemd[556]: Reloading... machine # [ 40.560488] systemd[556]: Reloading finished in 102 ms. machine # [ 40.576163] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 40.587331] systemd[556]: Starting usertest.service... machine # [ 40.613195] systemd[556]: Finished usertest.service. machine # [ 40.615757] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 40.618006] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 40.619930] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 40.651191] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 40.655467] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 40.657936] systemd[1]: Stopping Reactivate sysinit units... machine # [ 40.658816] systemd[1]: Reached target Reactivate sysinit units. machine # [ 40.665326] systemd[1]: Reached target Remote File Systems. machine # [ 40.668622] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 40.670122] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 40.672839] systemd[1]: Starting linger-users.service... machine # [ 40.677484] systemd[1]: Reached target Local File Systems. machine # [ 40.686357] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 40.687986] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 40.694218] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 40.695544] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 40.701020] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 40.702671] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 40.703951] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 40.759479] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 40.763352] systemd[1]: Finished linger-users.service. machine # [ 40.887334] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 40.890378] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 41.145688] nixos[2463]: finished switching to system configuration /nix/store/vz3p3qghngmbhhz2rlgx0vrpbfcnl27b-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.74 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 41.193626] sudo[2602]: 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.218501] sudo[2602]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 41.242323] sudo[2602]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.10 seconds) machine: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 41.440572] nixos[2611]: switching to system configuration /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test machine # [ 41.443233] systemd[1]: Stopped target Remote File Systems. machine # [ 41.446110] systemd[1]: Stopped target Local File Systems. machine # [ 41.701539] systemd[1]: Reload requested from client PID 2611 ('.switch-to-conf') (unit backdoor.service)... machine # [ 41.703442] systemd[1]: Reloading... machine # [ 41.998463] systemd[1]: Reloading finished in 293 ms. machine # [ 42.084452] systemd[556]: Stopped usertest.service. machine # [ 42.088141] systemd[556]: Reexecution requested from client PID 2681 ('switch-to-confi')... machine # [ 42.089528] systemd[556]: Reexecuting. machine # [ 42.147557] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.257242] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.264134] systemd[556]: Reload requested from client PID 2681 ('switch-to-confi')... machine # [ 42.267543] systemd[556]: Reloading... machine # [ 42.374228] systemd[556]: Reloading finished in 106 ms. machine # [ 42.384160] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.393878] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 42.396118] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 42.398305] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 42.427141] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 42.430625] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 42.432731] systemd[1]: Stopping Reactivate sysinit units... machine # [ 42.433617] systemd[1]: Reached target Reactivate sysinit units. machine # [ 42.438749] systemd[1]: Reached target Remote File Systems. machine # [ 42.442933] systemd[1]: Starting linger-users.service... machine # [ 42.449682] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 42.451101] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 42.455672] systemd[1]: Reached target Local File Systems. machine # [ 42.458450] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 42.465075] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 42.466017] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 42.467288] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 42.482875] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 42.486604] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 42.487944] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 42.525192] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 42.529099] systemd[1]: Finished linger-users.service. machine # [ 42.661435] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 42.663269] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 42.919468] nixos[2611]: finished switching to system configuration /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.68 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 42.961866] sudo[2749]: 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 # [ 42.975981] sudo[2749]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 42.989796] sudo[2749]: 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 # [ 43.019603] sudo[2755]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 43.030648] sudo[2755]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 43.039367] sudo[2755]: 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/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 43.067910] sudo[2761]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 43.078573] sudo[2761]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 43.087045] sudo[2761]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-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 # [ 43.114425] sudo[2767]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 43.124542] sudo[2767]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 43.132582] sudo[2767]: 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 # [ 43.158672] sudo[2773]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload machine # [ 43.168750] sudo[2773]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 43.176391] systemd[556]: Reload requested from client PID 2778 ('systemctl')... machine # [ 43.180192] systemd[556]: Reloading... machine # [ 43.287104] systemd[556]: Reloading finished in 108 ms. machine # [ 43.301619] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.312883] sudo[2773]: 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 # [ 43.339289] sudo[2783]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service machine # [ 43.348916] sudo[2783]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 43.361872] systemd[556]: Starting migrated.service... machine # [ 43.385937] systemd[556]: Finished migrated.service. machine # [ 43.390210] sudo[2783]: 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 # [ 43.416778] sudo[2791]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 43.426326] sudo[2791]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 43.436307] 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 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 # [ 43.462145] sudo[2797]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 43.471403] sudo[2797]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 43.478772] sudo[2797]: 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 # [ 43.504489] sudo[2803]: 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 # [ 43.514575] sudo[2803]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 43.530181] sudo[2803]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds) machine: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1 machine # [ 43.700952] nixos[2812]: switching to system configuration /nix/store/w9ppc0823z52jrrkk9bqc8zd4i2ph7p5-nixos-system-machine-test machine # [ 43.703667] systemd[1]: Stopped target Local File Systems. machine # [ 43.706029] systemd[1]: Stopped target Remote File Systems. machine # [ 43.990007] systemd[1]: Reload requested from client PID 2812 ('.switch-to-conf') (unit backdoor.service)... machine # [ 43.991961] systemd[1]: Reloading... machine # [ 44.293888] systemd[1]: Reloading finished in 300 ms. machine # [ 44.379733] systemd[556]: Reexecution requested from client PID 2881 ('switch-to-confi')... machine # [ 44.383784] systemd[556]: Reexecuting. machine # [ 44.444571] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 44.611668] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 44.619433] systemd[556]: Reload requested from client PID 2881 ('switch-to-confi')... machine # [ 44.622862] systemd[556]: Reloading... machine # [ 44.729015] systemd[556]: Reloading finished in 107 ms. machine # [ 44.744183] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 44.753753] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 44.756069] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 44.758321] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 44.811182] systemd[556]: Reload requested from client PID 2894 ('systemctl') (unit nixos-activation.service)... machine # [ 44.815899] systemd[556]: Reloading... machine # [ 44.926359] systemd[556]: Reloading finished in 111 ms. machine # [ 44.940174] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 44.959886] systemd[556]: Stopped migrated.service. machine # [ 44.970191] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 44.973077] systemd[556]: Reload requested from client PID 2881 ('switch-to-confi')... machine # [ 44.976849] systemd[556]: Reloading... machine # [ 45.081530] systemd[556]: Reloading finished in 105 ms. machine # [ 45.096181] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.106828] systemd[556]: Starting migrated.service... machine # [ 45.135113] systemd[556]: Finished migrated.service. machine # [ 45.139573] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 45.141483] systemd[1]: Stopping Reactivate sysinit units... machine # [ 45.142368] systemd[1]: Reached target Reactivate sysinit units. machine # [ 45.145850] systemd[1]: Reached target Remote File Systems. machine # [ 45.149915] systemd[1]: Reached target Local File Systems. machine # [ 45.152778] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 45.157300] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 45.158198] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 45.159414] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 45.173238] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 45.174928] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 45.177408] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 45.178667] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 45.179950] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 45.332203] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 45.336538] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 45.338830] systemd[1]: Starting linger-users.service... machine # [ 45.379724] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 45.381801] systemd[1]: Finished linger-users.service. machine # [ 45.637662] nixos[2812]: finished switching to system configuration /nix/store/w9ppc0823z52jrrkk9bqc8zd4i2ph7p5-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 2.11 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 45.679404] sudo[2964]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 45.695336] sudo[2964]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 45.708406] sudo[2964]: 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 # [ 45.743419] sudo[2970]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 45.755220] sudo[2970]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 45.772368] sudo[2970]: 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 # [ 45.798243] sudo[2976]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 45.807617] sudo[2976]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 45.814977] sudo[2976]: 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/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 45.984369] nixos[2985]: switching to system configuration /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test machine # [ 45.987986] systemd[1]: Stopped target Local File Systems. machine # [ 45.989578] systemd[1]: Stopped target Remote File Systems. machine # [ 46.248673] systemd[1]: Reload requested from client PID 2985 ('.switch-to-conf') (unit backdoor.service)... machine # [ 46.250252] systemd[1]: Reloading... machine # [ 46.549698] systemd[1]: Reloading finished in 297 ms. machine # [ 46.631975] systemd[556]: Stopped migrated.service. machine # [ 46.635716] systemd[556]: Reexecution requested from client PID 3054 ('switch-to-confi')... machine # [ 46.638043] systemd[556]: Reexecuting. machine # [ 46.695581] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.807860] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.815527] systemd[556]: Reload requested from client PID 3054 ('switch-to-confi')... machine # [ 46.818672] systemd[556]: Reloading... machine # [ 46.923212] systemd[556]: Reloading finished in 105 ms. machine # [ 46.940157] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.949994] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 46.952353] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 46.954443] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 46.982904] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 46.986669] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 46.989288] systemd[1]: Stopping Reactivate sysinit units... machine # [ 46.990174] systemd[1]: Reached target Reactivate sysinit units. machine # [ 46.993299] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.997404] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 46.998762] systemd[1]: Reached target Remote File Systems. machine # [ 47.002790] systemd[1]: Starting linger-users.service... machine # [ 47.012415] systemd[1]: Reached target Local File Systems. machine # [ 47.013404] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 47.014862] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 47.015696] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 47.029035] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 47.031345] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 47.033168] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 47.034476] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 47.092185] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 47.095896] systemd[1]: Finished linger-users.service. machine # [ 47.216676] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 47.219134] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 47.474990] nixos[2985]: finished switching to system configuration /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.66 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 47.518253] sudo[3122]: 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.533786] sudo[3122]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.548657] sudo[3122]: 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 # [ 47.580939] sudo[3128]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 47.592983] sudo[3128]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.600487] sudo[3128]: 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/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 47.628343] sudo[3134]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 47.638377] sudo[3134]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.646045] sudo[3134]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-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 # [ 47.673081] sudo[3140]: 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 # [ 47.683052] sudo[3140]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.690959] sudo[3140]: 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 # [ 47.717295] sudo[3146]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload machine # [ 47.728721] sudo[3146]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.737001] systemd[556]: Reload requested from client PID 3151 ('systemctl')... machine # [ 47.740223] systemd[556]: Reloading... machine # [ 47.851273] systemd[556]: Reloading finished in 111 ms. machine # [ 47.868157] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 47.882013] sudo[3146]: 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 # [ 47.909779] sudo[3156]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service machine # [ 47.919567] sudo[3156]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.932921] systemd[556]: Starting migrated.service... machine # [ 47.958810] systemd[556]: Finished migrated.service. machine # [ 47.962868] sudo[3156]: 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.989666] sudo[3164]: 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.999506] sudo[3164]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 48.009725] 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 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 # [ 48.036341] sudo[3170]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 48.045752] sudo[3170]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 48.053585] sudo[3170]: 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 # [ 48.079622] sudo[3176]: 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 # [ 48.089618] sudo[3176]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 48.105785] sudo[3176]: 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/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1 machine # [ 48.280101] nixos[3185]: switching to system configuration /nix/store/mfq06f27wygvc6wj3gqkvipd141gp5lw-nixos-system-machine-test machine # [ 48.282845] systemd[1]: Stopped target Local File Systems. machine # [ 48.285418] systemd[1]: Stopped target Remote File Systems. machine # [ 48.557854] systemd[1]: Reload requested from client PID 3185 ('.switch-to-conf') (unit backdoor.service)... machine # [ 48.559760] systemd[1]: Reloading... machine # [ 48.862464] systemd[1]: Reloading finished in 301 ms. machine # [ 48.956468] systemd[556]: Reexecution requested from client PID 3254 ('switch-to-confi')... machine # [ 48.961104] systemd[556]: Reexecuting. machine # [ 49.007039] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.118784] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.125503] systemd[556]: Reload requested from client PID 3254 ('switch-to-confi')... machine # [ 49.129117] systemd[556]: Reloading... machine # [ 49.235152] systemd[556]: Reloading finished in 107 ms. machine # [ 49.248157] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.257673] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 49.260042] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 49.262195] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 49.295431] systemd[556]: Reload requested from client PID 3266 ('systemctl') (unit nixos-activation.service)... machine # [ 49.299694] systemd[556]: Reloading... machine # [ 49.410235] systemd[556]: Reloading finished in 111 ms. machine # [ 49.425956] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.441686] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 49.444480] systemd[556]: Reload requested from client PID 3254 ('switch-to-confi')... machine # [ 49.448147] systemd[556]: Reloading... machine # [ 49.554317] systemd[556]: Reloading finished in 107 ms. machine # [ 49.568172] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 49.579104] systemd[556]: Stopped migrated.service. machine # [ 49.579947] systemd[556]: Stopping migrated.service... machine # [ 49.582120] systemd[556]: Starting migrated.service... machine # [ 49.606095] systemd[556]: Finished migrated.service. machine # [ 49.612889] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 49.613869] systemd[1]: Stopping Reactivate sysinit units... machine # [ 49.614669] systemd[1]: Reached target Reactivate sysinit units. machine # [ 49.619116] systemd[1]: Reached target Local File Systems. machine # [ 49.622420] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 49.626922] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 49.627837] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 49.637321] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 49.639572] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 49.646854] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 49.648204] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 49.649409] systemd[1]: Reached target Remote File Systems. machine # [ 49.650180] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 49.651429] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 49.799892] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 49.805013] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 49.807004] systemd[1]: Starting linger-users.service... machine # [ 49.847999] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 49.851028] systemd[1]: Finished linger-users.service. machine # [ 50.105684] nixos[3185]: finished switching to system configuration /nix/store/mfq06f27wygvc6wj3gqkvipd141gp5lw-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-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 # [ 50.146105] sudo[3335]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 50.161042] sudo[3335]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 50.174123] sudo[3335]: 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 # [ 50.206533] sudo[3341]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 50.218579] sudo[3341]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 50.237345] sudo[3341]: 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 # [ 50.264414] sudo[3347]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 50.273609] sudo[3347]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 50.281187] sudo[3347]: 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/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 50.454820] nixos[3356]: switching to system configuration /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test machine # [ 50.457301] systemd[1]: Stopped target Remote File Systems. machine # [ 50.459254] systemd[1]: Stopped target Local File Systems. machine # [ 50.723210] systemd[1]: Reload requested from client PID 3356 ('.switch-to-conf') (unit backdoor.service)... machine # [ 50.725341] systemd[1]: Reloading... machine # [ 51.027141] systemd[1]: Reloading finished in 300 ms. machine # [ 51.113337] systemd[556]: Stopped migrated.service. machine # [ 51.116839] systemd[556]: Reexecution requested from client PID 3425 ('switch-to-confi')... machine # [ 51.119347] systemd[556]: Reexecuting. machine # [ 51.179343] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.294585] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.301251] systemd[556]: Reload requested from client PID 3425 ('switch-to-confi')... machine # [ 51.303644] systemd[556]: Reloading... machine # [ 51.409803] systemd[556]: Reloading finished in 105 ms. machine # [ 51.425665] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.433825] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 51.436086] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 51.438216] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 51.466874] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 51.470806] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 51.473232] systemd[1]: Stopping Reactivate sysinit units... machine # [ 51.474105] systemd[1]: Reached target Reactivate sysinit units. machine # [ 51.475783] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.478293] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 51.482280] systemd[1]: Reached target Remote File Systems. machine # [ 51.486991] systemd[1]: Starting linger-users.service... machine # [ 51.492526] systemd[1]: Reached target Local File Systems. machine # [ 51.500897] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 51.507337] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 51.510958] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 51.516965] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 51.519219] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 51.526849] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.530323] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 51.568923] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 51.572136] systemd[1]: Finished linger-users.service. machine # [ 51.705086] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 51.706953] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 51.963356] nixos[3356]: finished switching to system configuration /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.69 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 52.004951] sudo[3493]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 52.019500] sudo[3493]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.033707] sudo[3493]: 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 # [ 52.065522] sudo[3499]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 52.077005] sudo[3499]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.085856] sudo[3499]: 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/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 52.114632] sudo[3505]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 52.124960] sudo[3505]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.132638] sudo[3505]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 52.159363] sudo[3511]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 52.169536] sudo[3511]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.177192] sudo[3511]: 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 # [ 52.203797] sudo[3517]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload machine # [ 52.213671] sudo[3517]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.222088] systemd[556]: Reload requested from client PID 3522 ('systemctl')... machine # [ 52.224183] systemd[556]: Reloading... machine # [ 52.330787] systemd[556]: Reloading finished in 105 ms. machine # [ 52.348160] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.361068] sudo[3517]: 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 # [ 52.388460] sudo[3527]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service machine # [ 52.398927] sudo[3527]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.411266] systemd[556]: Starting migrated.service... machine # [ 52.438319] systemd[556]: Finished migrated.service. machine # [ 52.442190] sudo[3527]: 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 # [ 52.471836] sudo[3535]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 52.481939] sudo[3535]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.492097] 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 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 # [ 52.518599] sudo[3541]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 52.528861] sudo[3541]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.535706] sudo[3541]: 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 # [ 52.561864] sudo[3547]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 52.571558] sudo[3547]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 52.586650] sudo[3547]: 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/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1 machine # [ 52.759312] nixos[3556]: switching to system configuration /nix/store/mjqb16rzwsim6hz0v0z90iszcx9fa7v2-nixos-system-machine-test machine # [ 52.762178] systemd[1]: Stopped target Remote File Systems. machine # [ 52.764542] systemd[1]: Stopped target Local File Systems. machine # [ 53.034913] systemd[1]: Reload requested from client PID 3556 ('.switch-to-conf') (unit backdoor.service)... machine # [ 53.037014] systemd[1]: Reloading... machine # [ 53.332996] systemd[1]: Reloading finished in 294 ms. machine # [ 53.420435] systemd[556]: Reexecution requested from client PID 3625 ('switch-to-confi')... machine # [ 53.421839] systemd[556]: Reexecuting. machine # [ 53.483807] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.605765] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.612253] systemd[556]: Reload requested from client PID 3625 ('switch-to-confi')... machine # [ 53.615798] systemd[556]: Reloading... machine # [ 53.722524] systemd[556]: Reloading finished in 107 ms. machine # [ 53.740164] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.750750] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 53.753052] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 53.755117] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 53.791761] systemd[556]: Reload requested from client PID 3637 ('systemctl') (unit nixos-activation.service)... machine # [ 53.796241] systemd[556]: Reloading... machine # [ 53.909426] systemd[556]: Reloading finished in 114 ms. machine # [ 53.924176] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.941582] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 53.944642] systemd[556]: Reload requested from client PID 3625 ('switch-to-confi')... machine # [ 53.946934] systemd[556]: Reloading... machine # [ 54.055665] systemd[556]: Reloading finished in 107 ms. machine # [ 54.072170] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 54.083286] systemd[556]: Reloading migrated.service... machine # [ 54.109134] systemd[556]: Reloaded migrated.service. machine # [ 54.115957] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 54.119011] systemd[1]: Stopping Reactivate sysinit units... machine # [ 54.119891] systemd[1]: Reached target Reactivate sysinit units. machine # [ 54.124275] systemd[1]: Reached target Remote File Systems. machine # [ 54.126092] systemd[1]: Starting linger-users.service... machine # [ 54.135574] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 54.138862] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 54.143044] systemd[1]: Reached target Local File Systems. machine # [ 54.150045] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 54.156221] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 54.157237] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 54.158535] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 54.169995] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 54.171635] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 54.175801] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 54.215728] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 54.219645] systemd[1]: Finished linger-users.service. machine # [ 54.349387] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 54.352351] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 54.607742] nixos[3556]: finished switching to system configuration /nix/store/mjqb16rzwsim6hz0v0z90iszcx9fa7v2-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 2.03 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 54.649446] sudo[3706]: 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 # [ 54.665408] sudo[3706]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 54.678980] sudo[3706]: 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 # [ 54.711449] sudo[3712]: 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 # [ 54.722640] sudo[3712]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 54.730874] sudo[3712]: 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/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 54.899990] nixos[3721]: switching to system configuration /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test machine # [ 54.902789] systemd[1]: Stopped target Local File Systems. machine # [ 54.904796] systemd[1]: Stopped target Remote File Systems. machine # [ 55.164549] systemd[1]: Reload requested from client PID 3721 ('.switch-to-conf') (unit backdoor.service)... machine # [ 55.166146] systemd[1]: Reloading... machine # [ 55.461435] systemd[1]: Reloading finished in 294 ms. machine # [ 55.544412] systemd[556]: Stopped migrated.service. machine # [ 55.548081] systemd[556]: Reexecution requested from client PID 3789 ('switch-to-confi')... machine # [ 55.549424] systemd[556]: Reexecuting. machine # [ 55.611309] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.726163] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.732456] systemd[556]: Reload requested from client PID 3789 ('switch-to-confi')... machine # [ 55.735934] systemd[556]: Reloading... machine # [ 55.839661] systemd[556]: Reloading finished in 104 ms. machine # [ 55.856162] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.865142] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 55.867428] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 55.869588] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 55.898650] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 55.902010] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 55.904969] systemd[1]: Stopping Reactivate sysinit units... machine # [ 55.905821] systemd[1]: Reached target Reactivate sysinit units. machine # [ 55.906689] systemd[1]: Reached target Remote File Systems. machine # [ 55.912799] systemd[1]: Starting linger-users.service... machine # [ 55.916887] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.918225] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 55.927482] systemd[1]: Reached target Local File Systems. machine # [ 55.929526] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 55.936439] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 55.937331] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 55.938552] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 55.952323] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 55.959246] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.961177] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.992873] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 55.995095] systemd[1]: Finished linger-users.service. machine # [ 56.131959] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 56.134705] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 56.389884] nixos[3721]: finished switching to system configuration /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.66 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 56.430166] sudo[3858]: 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.444679] sudo[3858]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.458392] sudo[3858]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 56.488507] sudo[3864]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 56.499325] sudo[3864]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.507487] sudo[3864]: 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/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 56.534409] sudo[3870]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 56.544596] sudo[3870]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.551751] sudo[3870]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.04 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 56.577093] sudo[3876]: 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 # [ 56.586477] sudo[3876]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.593698] sudo[3876]: 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 # [ 56.618457] sudo[3882]: 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.627700] sudo[3882]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.635596] systemd[556]: Reload requested from client PID 3887 ('systemctl')... machine # [ 56.639513] systemd[556]: Reloading... machine # [ 56.743918] systemd[556]: Reloading finished in 105 ms. machine # [ 56.760170] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 56.774093] sudo[3882]: 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 # [ 56.800533] sudo[3892]: 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.809871] sudo[3892]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.822786] systemd[556]: Starting migrated.service... machine # [ 56.846264] systemd[556]: Finished migrated.service. machine # [ 56.850334] sudo[3892]: 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.876847] sudo[3900]: 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.886484] sudo[3900]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.896091] 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 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.921822] sudo[3906]: 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.931287] sudo[3906]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.938628] sudo[3906]: 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.964490] sudo[3912]: 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.974227] sudo[3912]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.989914] sudo[3912]: 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/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1 machine # [ 57.161153] nixos[3921]: switching to system configuration /nix/store/jxvav44qblh48ahylx7cwcchmnf7648l-nixos-system-machine-test machine # [ 57.165129] systemd[1]: Stopped target Remote File Systems. machine # [ 57.165997] systemd[1]: Stopped target Local File Systems. machine # [ 57.439223] systemd[1]: Reload requested from client PID 3921 ('.switch-to-conf') (unit backdoor.service)... machine # [ 57.441309] systemd[1]: Reloading... machine # [ 57.735027] systemd[1]: Reloading finished in 292 ms. machine # [ 57.824529] systemd[556]: Reexecution requested from client PID 3989 ('switch-to-confi')... machine # [ 57.825924] systemd[556]: Reexecuting. machine # [ 57.883131] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.998251] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 58.004841] systemd[556]: Reload requested from client PID 3989 ('switch-to-confi')... machine # [ 58.007136] systemd[556]: Reloading... machine # [ 58.112980] systemd[556]: Reloading finished in 104 ms. machine # [ 58.128148] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 58.137890] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 58.140103] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 58.142190] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 58.175376] systemd[556]: Reload requested from client PID 4002 ('systemctl') (unit nixos-activation.service)... machine # [ 58.179650] systemd[556]: Reloading... machine # [ 58.289309] systemd[556]: Reloading finished in 110 ms. machine # [ 58.304163] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 58.322633] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 58.325566] systemd[556]: Reload requested from client PID 3989 ('switch-to-confi')... machine # [ 58.329284] systemd[556]: Reloading... machine # [ 58.433910] systemd[556]: Reloading finished in 105 ms. machine # [ 58.448150] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 58.461763] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 58.462702] systemd[1]: Stopping Reactivate sysinit units... machine # [ 58.463499] systemd[1]: Reached target Reactivate sysinit units. machine # [ 58.465874] systemd[1]: Reached target Remote File Systems. machine # [ 58.468921] systemd[1]: Reached target Local File Systems. machine # [ 58.472162] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 58.476083] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 58.477026] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 58.478242] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 58.489392] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 58.491003] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 58.497263] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 58.498499] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 58.499754] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 58.652047] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 58.656439] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 58.659084] systemd[1]: Starting linger-users.service... machine # [ 58.699052] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 58.701913] systemd[1]: Finished linger-users.service. machine # [ 58.957920] nixos[3921]: finished switching to system configuration /nix/store/jxvav44qblh48ahylx7cwcchmnf7648l-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 1.97 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 58.998279] sudo[4069]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 59.013306] sudo[4069]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 59.027202] sudo[4069]: 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 # [ 59.061102] sudo[4075]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 59.071823] sudo[4075]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 59.079833] sudo[4075]: 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/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 59.250877] nixos[4084]: switching to system configuration /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test machine # [ 59.253442] systemd[1]: Stopped target Remote File Systems. machine # [ 59.255638] systemd[1]: Stopped target Local File Systems. machine # [ 59.512948] systemd[1]: Reload requested from client PID 4084 ('.switch-to-conf') (unit backdoor.service)... machine # [ 59.514831] systemd[1]: Reloading... machine # [ 59.812949] systemd[1]: Reloading finished in 296 ms. machine # [ 59.897330] systemd[556]: Stopped migrated.service. machine # [ 59.900802] systemd[556]: Reexecution requested from client PID 4153 ('switch-to-confi')... machine # [ 59.903263] systemd[556]: Reexecuting. machine # [ 59.958901] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 60.067203] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 60.075023] systemd[556]: Reload requested from client PID 4153 ('switch-to-confi')... machine # [ 60.078035] systemd[556]: Reloading... machine # [ 60.181016] systemd[556]: Reloading finished in 104 ms. machine # [ 60.196176] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 60.206063] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 60.208068] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 60.210428] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 60.243103] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 60.247032] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 60.249728] systemd[1]: Stopping Reactivate sysinit units... machine # [ 60.250574] systemd[1]: Reached target Reactivate sysinit units. machine # [ 60.253890] systemd[1]: Reached target Remote File Systems. machine # [ 60.257141] systemd[1]: Starting linger-users.service... machine # [ 60.264375] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 60.265741] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 60.277251] systemd[1]: Reached target Local File Systems. machine # [ 60.278107] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 60.279579] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 60.287359] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 60.289561] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 60.291775] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 60.300312] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 60.304399] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 60.346722] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 60.348496] systemd[1]: Finished linger-users.service. machine # [ 60.474790] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 60.476867] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 60.734171] nixos[4084]: finished switching to system configuration /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.66 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 60.773787] sudo[4221]: 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.789226] sudo[4221]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 60.802429] sudo[4221]: 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.833746] sudo[4227]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user machine # [ 60.844942] sudo[4227]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 60.853486] sudo[4227]: 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.05 seconds) machine: must succeed: sudo -u usertest cp /nix/store/gj8i3dxm5jb7nzvvpbkliw0syyzbr40h-migrated.service ~usertest/.local/share/systemd/user/migrated.service machine # [ 60.880847] sudo[4233]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/gj8i3dxm5jb7nzvvpbkliw0syyzbr40h-migrated.service /home/usertest/.local/share/systemd/user/migrated.service machine # [ 60.890815] sudo[4233]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 60.898379] sudo[4233]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/gj8i3dxm5jb7nzvvpbkliw0syyzbr40h-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 60.924328] sudo[4239]: 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.933598] sudo[4239]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 60.941501] systemd[556]: Reload requested from client PID 4244 ('systemctl')... machine # [ 60.945363] systemd[556]: Reloading... machine # [ 61.051360] systemd[556]: Reloading finished in 107 ms. machine # [ 61.068160] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 61.081500] sudo[4239]: 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 # [ 61.107799] sudo[4249]: 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 # [ 61.117603] sudo[4249]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 61.131729] systemd[556]: Starting migrated.service... machine # [ 61.157335] systemd[556]: Finished migrated.service. machine # [ 61.161811] sudo[4249]: 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 # [ 61.187989] sudo[4257]: 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 # [ 61.197693] sudo[4257]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 61.207749] 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 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 # [ 61.233531] sudo[4263]: 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 # [ 61.242808] sudo[4263]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 61.250007] sudo[4263]: 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 # [ 61.275672] sudo[4269]: 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 # [ 61.285595] sudo[4269]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 61.304489] sudo[4269]: 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/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 61.477600] nixos[4278]: switching to system configuration /nix/store/70hdcpdm9dg652y370hdfiqha4522100-nixos-system-machine-test machine # [ 61.480845] systemd[1]: Stopped target Remote File Systems. machine # [ 61.482427] systemd[1]: Stopped target Local File Systems. machine # [ 61.757303] systemd[1]: Reload requested from client PID 4278 ('.switch-to-conf') (unit backdoor.service)... machine # [ 61.759244] systemd[1]: Reloading... machine # [ 62.059826] systemd[1]: Reloading finished in 299 ms. machine # [ 62.147494] systemd[556]: Reexecution requested from client PID 4347 ('switch-to-confi')... machine # [ 62.151201] systemd[556]: Reexecuting. machine # [ 62.198929] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.310278] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.316592] systemd[556]: Reload requested from client PID 4347 ('switch-to-confi')... machine # [ 62.318942] systemd[556]: Reloading... machine # [ 62.423867] systemd[556]: Reloading finished in 103 ms. machine # [ 62.440161] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.450686] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 62.451708] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 62.456156] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 62.486857] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 62.489135] systemd[556]: Reload requested from client PID 4347 ('switch-to-confi')... machine # [ 62.490513] systemd[556]: Reloading... machine # [ 62.596100] systemd[556]: Reloading finished in 103 ms. machine # [ 62.612164] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.621609] systemd[556]: Stopped migrated.service. machine # [ 62.623535] systemd[556]: Stopping migrated.service... machine # [ 62.625297] systemd[556]: Starting migrated.service... machine # [ 62.650115] systemd[556]: Finished migrated.service. machine # [ 62.656452] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 62.660727] systemd[1]: Stopping Reactivate sysinit units... machine # [ 62.661607] systemd[1]: Reached target Reactivate sysinit units. machine # [ 62.662441] systemd[1]: Reached target Remote File Systems. machine # [ 62.666851] systemd[1]: Starting linger-users.service... machine # [ 62.667675] systemd[1]: Reached target Local File Systems. machine # [ 62.677128] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.683265] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 62.686965] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 62.694243] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 62.695124] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 62.697846] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 62.701196] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 62.702803] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.708877] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.755585] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 62.756932] systemd[1]: Finished linger-users.service. machine # [ 62.886592] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 62.889409] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 63.145648] nixos[4278]: finished switching to system configuration /nix/store/70hdcpdm9dg652y370hdfiqha4522100-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.85 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 63.184716] sudo[4421]: 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 # [ 63.197046] sudo[4421]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 63.207086] sudo[4421]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 63.234307] sudo[4427]: 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 # [ 63.245012] sudo[4427]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 63.260995] sudo[4427]: 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: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 63.287506] sudo[4433]: 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.297145] sudo[4433]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 63.304900] sudo[4433]: 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/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 63.474756] nixos[4442]: switching to system configuration /nix/store/70hdcpdm9dg652y370hdfiqha4522100-nixos-system-machine-test machine # [ 63.477302] systemd[1]: Stopped target Local File Systems. machine # [ 63.479885] systemd[1]: Stopped target Remote File Systems. machine # [ 63.738792] systemd[1]: Reload requested from client PID 4442 ('.switch-to-conf') (unit backdoor.service)... machine # [ 63.740923] systemd[1]: Reloading... machine # [ 64.037544] systemd[1]: Reloading finished in 295 ms. machine # [ 64.119824] systemd[556]: Reexecution requested from client PID 4510 ('switch-to-confi')... machine # [ 64.122106] systemd[556]: Reexecuting. machine # [ 64.179131] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 64.291012] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 64.298236] systemd[556]: Reload requested from client PID 4510 ('switch-to-confi')... machine # [ 64.299555] systemd[556]: Reloading... machine # [ 64.405179] systemd[556]: Reloading finished in 104 ms. machine # [ 64.420162] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 64.429704] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 64.431968] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 64.434116] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 64.462432] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 64.466180] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 64.468808] systemd[1]: Stopping Reactivate sysinit units... machine # [ 64.469666] systemd[1]: Reached target Reactivate sysinit units. machine # [ 64.472341] systemd[1]: Reached target Remote File Systems. machine # [ 64.475836] systemd[1]: Starting linger-users.service... machine # [ 64.485372] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 64.486746] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 64.491179] systemd[1]: Reached target Local File Systems. machine # [ 64.492114] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 64.500074] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 64.501069] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 64.514737] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 64.520188] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 64.521898] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 64.523144] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 64.565160] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 64.567447] systemd[1]: Finished linger-users.service. machine # [ 64.699751] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 64.701917] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 64.958488] nixos[4442]: finished switching to system configuration /nix/store/70hdcpdm9dg652y370hdfiqha4522100-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.66 seconds) machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd machine # [ 64.999943] sudo[4579]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd machine # [ 65.014224] sudo[4579]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 65.025283] sudo[4579]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.06 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 65.060788] sudo[4585]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload machine # [ 65.071991] sudo[4585]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 65.080967] systemd[556]: Reload requested from client PID 4590 ('systemctl')... machine # [ 65.084301] systemd[556]: Reloading... machine # [ 65.194043] systemd[556]: Reloading finished in 110 ms. machine # [ 65.208217] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.220068] sudo[4585]: 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: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 65.390389] nixos[4598]: switching to system configuration /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test machine # [ 65.393275] systemd[1]: Stopped target Remote File Systems. machine # [ 65.395008] systemd[1]: Stopped target Local File Systems. machine # [ 65.652310] systemd[1]: Reload requested from client PID 4598 ('.switch-to-conf') (unit backdoor.service)... machine # [ 65.653940] systemd[1]: Reloading... machine # [ 65.947336] systemd[1]: Reloading finished in 292 ms. machine # [ 66.034417] systemd[556]: Stopped migrated.service. machine # [ 66.037651] systemd[556]: Reexecution requested from client PID 4667 ('switch-to-confi')... machine # [ 66.038979] systemd[556]: Reexecuting. machine # [ 66.103346] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 66.217431] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 66.224089] systemd[556]: Reload requested from client PID 4667 ('switch-to-confi')... machine # [ 66.225391] systemd[556]: Reloading... machine # [ 66.329325] systemd[556]: Reloading finished in 101 ms. machine # [ 66.344152] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 66.353878] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 66.356170] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 66.358321] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 66.391049] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 66.395311] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 66.396794] systemd[1]: Stopping Reactivate sysinit units... machine # [ 66.397621] systemd[1]: Reached target Reactivate sysinit units. machine # [ 66.401225] systemd[1]: Reached target Remote File Systems. machine # [ 66.405339] systemd[1]: Starting linger-users.service... machine # [ 66.412344] systemd[1]: Reached target Local File Systems. machine # [ 66.416803] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 66.422026] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 66.422895] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 66.430988] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 66.436807] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 66.438415] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 66.439679] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 66.444126] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 66.445487] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 66.494012] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 66.497937] systemd[1]: Finished linger-users.service. machine # [ 66.619832] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 66.622938] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 66.878209] nixos[4598]: finished switching to system configuration /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.66 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 66.919132] sudo[4735]: 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.933738] sudo[4735]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 66.943882] sudo[4735]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds) machine: must succeed: sudo -u usertest cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 66.977249] sudo[4741]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 66.988785] sudo[4741]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 66.996687] sudo[4741]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/pmwdbk1amzfws7riqyq3cqnpvm3l37lp-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 # [ 67.024297] sudo[4747]: 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 # [ 67.034008] sudo[4747]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 67.040932] sudo[4747]: 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 # [ 67.066054] sudo[4753]: 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.075247] sudo[4753]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 67.083234] systemd[556]: Reload requested from client PID 4758 ('systemctl')... machine # [ 67.085564] systemd[556]: Reloading... machine # [ 67.193121] systemd[556]: Reloading finished in 106 ms. machine # [ 67.208162] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 67.221949] sudo[4753]: 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.248937] sudo[4763]: 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.258426] sudo[4763]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 67.271287] systemd[556]: Starting migrated.service... machine # [ 67.298216] systemd[556]: Finished migrated.service. machine # [ 67.302139] sudo[4763]: 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 # [ 67.328506] sudo[4771]: 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.338023] sudo[4771]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 67.347867] 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 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 # [ 67.373696] sudo[4777]: 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.382938] sudo[4777]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 67.390334] sudo[4777]: 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 # [ 67.415562] sudo[4783]: 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.425318] sudo[4783]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 67.440465] sudo[4783]: 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/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 67.617029] nixos[4792]: switching to system configuration /nix/store/70hdcpdm9dg652y370hdfiqha4522100-nixos-system-machine-test machine # [ 67.619973] systemd[1]: Stopped target Local File Systems. machine # [ 67.622687] systemd[1]: Stopped target Remote File Systems. machine # [ 67.885859] systemd[1]: Reload requested from client PID 4792 ('.switch-to-conf') (unit backdoor.service)... machine # [ 67.887445] systemd[1]: Reloading... machine # [ 68.181550] systemd[1]: Reloading finished in 292 ms. machine # [ 68.263144] systemd[556]: Reexecution requested from client PID 4861 ('switch-to-confi')... machine # [ 68.265555] systemd[556]: Reexecuting. machine # [ 68.323805] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.437828] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.444467] systemd[556]: Reload requested from client PID 4861 ('switch-to-confi')... machine # [ 68.447092] systemd[556]: Reloading... machine # [ 68.555493] systemd[556]: Reloading finished in 107 ms. machine # [ 68.572163] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.583071] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 68.585415] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 68.587446] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 68.619824] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 68.622413] systemd[556]: Reload requested from client PID 4861 ('switch-to-confi')... machine # [ 68.623721] systemd[556]: Reloading... machine # [ 68.729075] systemd[556]: Reloading finished in 104 ms. machine # [ 68.744219] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 68.756835] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 68.758795] systemd[1]: Stopping Reactivate sysinit units... machine # [ 68.759641] systemd[1]: Reached target Reactivate sysinit units. machine # [ 68.763940] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 68.770724] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 68.776934] systemd[1]: Reached target Local File Systems. machine # [ 68.777790] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 68.779281] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 68.785184] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 68.786459] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 68.790652] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 68.796656] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 68.797996] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 68.799797] systemd[1]: Reached target Remote File Systems. machine # [ 68.944135] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 68.948473] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 68.950541] systemd[1]: Starting linger-users.service... machine # [ 68.991188] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 68.993117] systemd[1]: Finished linger-users.service. machine # [ 69.248799] nixos[4792]: finished switching to system configuration /nix/store/70hdcpdm9dg652y370hdfiqha4522100-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/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 show -p FragmentPath migrated.service machine # [ 69.289944] sudo[4933]: 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.305661] sudo[4933]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 69.328325] sudo[4933]: 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.361099] sudo[4939]: 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.371693] sudo[4939]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 69.379585] sudo[4939]: 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.405865] sudo[4945]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd machine # [ 69.415004] sudo[4945]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 69.422635] sudo[4945]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 69.448481] sudo[4951]: 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.458099] sudo[4951]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 69.465500] systemd[556]: Reload requested from client PID 4956 ('systemctl')... machine # [ 69.469302] systemd[556]: Reloading... machine # [ 69.573705] systemd[556]: Reloading finished in 105 ms. machine # [ 69.588147] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.601872] sudo[4951]: 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.628641] sudo[4961]: 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.638653] sudo[4961]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 69.649698] systemd[556]: Stopped migrated.service. machine # [ 69.653400] sudo[4961]: 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/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 69.820722] nixos[4970]: switching to system configuration /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test machine # [ 69.823451] systemd[1]: Stopped target Local File Systems. machine # [ 69.825885] systemd[1]: Stopped target Remote File Systems. machine # [ 70.081628] systemd[1]: Reload requested from client PID 4970 ('.switch-to-conf') (unit backdoor.service)... machine # [ 70.083658] systemd[1]: Reloading... machine # [ 70.382515] systemd[1]: Reloading finished in 297 ms. machine # [ 70.466730] systemd[556]: Reexecution requested from client PID 5039 ('switch-to-confi')... machine # [ 70.471797] systemd[556]: Reexecuting. machine # [ 70.527189] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 70.639754] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 70.646545] systemd[556]: Reload requested from client PID 5039 ('switch-to-confi')... machine # [ 70.649996] systemd[556]: Reloading... machine # [ 70.752976] systemd[556]: Reloading finished in 104 ms. machine # [ 70.769692] systemd[556]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 70.777572] systemd[556]: Stopped Run user-specific NixOS activation. machine # [ 70.779731] systemd[556]: Stopping Run user-specific NixOS activation... machine # [ 70.781887] systemd[556]: Starting Run user-specific NixOS activation... machine # [ 70.810777] systemd[556]: Finished Run user-specific NixOS activation. machine # [ 70.814531] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 70.817655] systemd[1]: Stopping Reactivate sysinit units... machine # [ 70.818555] systemd[1]: Reached target Reactivate sysinit units. machine # [ 70.823589] systemd[1]: Reached target Remote File Systems. machine # [ 70.826082] systemd[1]: Starting linger-users.service... machine # [ 70.832241] systemd[1]: Reached target Local File Systems. machine # [ 70.835222] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 70.840976] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 70.842349] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 70.853631] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 70.854585] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 70.859723] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 70.866341] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 70.867991] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 70.875944] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 70.913612] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 70.916454] systemd[1]: Finished linger-users.service. machine # [ 71.041749] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 71.044135] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 71.299621] nixos[4970]: finished switching to system configuration /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test machine: (finished: must succeed: /nix/store/1fm7dwdqf1hmqk2n4081nj7abcv7qh5r-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.65 seconds) (finished: subtest: user services, in 46.76 seconds) (finished: run the VM test script, in 73.63 seconds) test script finished in 73.67s cleanup kill QemuMachine (pid 45) machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14) machine # [2026-10-01T03:47:22Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-10-01T03:47:22Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-10-01T03:47:22Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.14 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