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: QEMU running (pid 45) machine # Disk image does not exist, creating the virtualisation disk image... machine # Formatting '/build/vm-state-machine/tmp.PZm8mCX9Zb', 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: ce7a81f5-595c-41cb-88d7-20c367377512 machine # Superblock backups stored on blocks: machine # 32768, 98304, 163840, 229376 machine # machine # Allocating group tables: 0/8 done machine # Writing inode tables: 0/8 done machine # Creating journal (8192 blocks): done machine # Writing superblocks and filesystem accounting information: 0/8 done machine # machine # Virtualisation disk image created. machine # Starting virtiofs daemons... machine # [2026-09-23T02:59:49Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-23T02:59:49Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-23T02:59:49Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-23T02:59:49Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-23T02:59:49Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-23T02:59:49Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-23T02:59:49Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-23T02:59:49Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-23T02:59:49Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-23T02:59:49Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-23T02:59:49Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-23T02:59:49Z INFO virtiofsd] Client connected, servicing requests machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] machine # [ 0.000000] Linux version 6.18.53 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 21 13:06:06 UTC 2026 machine # [ 0.000000] KASLR enabled machine # [ 0.000000] random: crng init done machine # [ 0.000000] Machine model: linux,dummy-virt machine # [ 0.000000] efi: UEFI not found. machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fdee700-0x7fdf1e7f] machine # [ 0.000000] Zone ranges: machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] DMA32 empty machine # [ 0.000000] Normal empty machine # [ 0.000000] Device empty machine # [ 0.000000] Movable zone start for each node machine # [ 0.000000] Early memory node ranges machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000 machine # [ 0.000000] psci: probing for conduit method from DT. machine # [ 0.000000] psci: PSCIv1.3 detected in firmware. machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs machine # [ 0.000000] psci: Trusted OS migration not required machine # [ 0.000000] psci: SMC Calling Convention v1.1 machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296 machine # [ 0.000000] Detected PIPT I-cache on CPU0 machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm) machine # [ 0.000000] CPU features: detected: GICv3 CPU interface machine # [ 0.000000] CPU features: detected: Spectre-v4 machine # [ 0.000000] CPU features: detected: Spectre-BHB machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38 machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23 machine # [ 0.000000] alternatives: applying boot alternatives machine # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/init regInfo=/nix/.ro-store/bdwdxh5sqvxfax67bhxax2p6cqlinanm-closure-info/registration console=ttyAMA0,115200n8 console=tty0 machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/bdwdxh5sqvxfax67bhxax2p6cqlinanm-closure-info/registration", will be passed to user space. machine # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB machine # [ 0.000000] software IO TLB: area num 1. machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB) machine # [ 0.000000] Fallback order for Node 0: 0 machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 machine # [ 0.000000] Policy zone: DMA machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 machine # [ 0.000000] allocated 2097152 bytes of page_ext machine # [ 0.000000] ftrace: allocating 74963 entries in 294 pages machine # [ 0.000000] ftrace: allocated 294 pages with 4 groups machine # [ 0.000000] rcu: Hierarchical RCU implementation. machine # [ 0.000000] rcu: RCU event tracing is enabled. machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. machine # [ 0.000000] Trampoline variant of Tasks RCU enabled. machine # [ 0.000000] Rude variant of Tasks RCU enabled. machine # [ 0.000000] Tracing variant of Tasks RCU enabled. machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 machine # [ 0.000000] GICv3: 256 SPIs implemented machine # [ 0.000000] GICv3: 0 Extended SPIs implemented machine # [ 0.000000] Root IRQ handler: gic_handle_irq machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @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.000032] arm-pv: using stolen time PV machine # [ 0.000420] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.000605] Console: colour dummy device 80x25 machine # [ 0.000614] printk: legacy console [tty0] enabled machine # [ 0.000801] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) machine # [ 0.000808] pid_max: default: 32768 minimum: 301 machine # [ 0.000884] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.001025] landlock: Up and running. machine # [ 0.001028] Yama: becoming mindful. machine # [ 0.001482] LSM support for eBPF active machine # [ 0.001629] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.001650] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.003440] rcu: Hierarchical SRCU implementation. machine # [ 0.003445] rcu: Max phase no-delay instances is 1000. machine # [ 0.004730] fsl-mc MSI: its@8080000 domain created machine # [ 0.004855] EFI services will not be available. machine # [ 0.004951] smp: Bringing up secondary CPUs ... machine # [ 0.004960] smp: Brought up 1 node, 1 CPU machine # [ 0.004964] SMP: Total of 1 processors activated. machine # [ 0.004966] CPU: All CPU(s) started at EL1 machine # [ 0.004980] CPU features: detected: Branch Target Identification machine # [ 0.004984] CPU features: detected: ARMv8.4 Translation Table Level machine # [ 0.004987] CPU features: detected: Instruction cache invalidation not required for I/D coherence machine # [ 0.004991] CPU features: detected: Data cache clean to the PoU not required for I/D coherence machine # [ 0.004994] CPU features: detected: Common not Private translations machine # [ 0.004997] CPU features: detected: CRC32 instructions machine # [ 0.005000] CPU features: detected: Data cache clean to Point of Deep Persistence machine # [ 0.005004] CPU features: detected: Data cache clean to Point of Persistence machine # [ 0.005007] CPU features: detected: Data independent timing control (DIT) machine # [ 0.005010] CPU features: detected: E0PD machine # [ 0.005013] CPU features: detected: Enhanced Counter Virtualization machine # [ 0.005016] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.005020] CPU features: detected: Enhanced Virtualization Traps machine # [ 0.005023] CPU features: detected: Fine Grained Traps machine # [ 0.005026] CPU features: detected: Generic authentication (architected QARMA5 algorithm) machine # [ 0.005031] CPU features: detected: RCpc load-acquire (LDAPR) machine # [ 0.005034] CPU features: detected: LSE atomic instructions machine # [ 0.005037] CPU features: detected: Privileged Access Never machine # [ 0.005040] CPU features: detected: PMUv3 machine # [ 0.005043] CPU features: detected: RAS Extension Support machine # [ 0.005046] CPU features: detected: RASv1p1 Extension Support machine # [ 0.005048] CPU features: detected: Random Number Generator machine # [ 0.005051] CPU features: detected: Speculation barrier (SB) machine # [ 0.005054] CPU features: detected: Stage-2 Force Write-Back machine # [ 0.005057] CPU features: detected: TLB range maintenance instructions machine # [ 0.005062] CPU features: detected: Speculative Store Bypassing Safe (SSBS) machine # [ 0.005099] alternatives: applying system-wide alternatives machine # [ 0.008225] CPU features: detected: BBM Level 2 without TLB conflict abort machine # [ 0.008423] Memory: 893780K/1048576K available (24448K kernel code, 7094K rwdata, 26592K rodata, 4736K init, 1107K bss, 113492K reserved, 32768K cma-reserved) machine # [ 0.008780] devtmpfs: initialized machine # [ 0.010477] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.010499] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.010687] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL machine # [ 0.010692] 0 pages in range for non-PLT usage machine # [ 0.010693] 508224 pages in range for PLT usage machine # [ 0.010785] pinctrl core: initialized pinctrl subsystem machine # [ 0.011559] DMI not present or invalid. machine # [ 0.014711] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.017074] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.017226] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.017393] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.017416] audit: initializing netlink subsys (disabled) machine # [ 0.018029] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.018031] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.018034] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.018037] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.018040] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.018075] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 machine # [ 0.018083] cpuidle: using governor ladder machine # [ 0.018089] cpuidle: using governor menu machine # [ 0.018308] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.018324] ASID allocator initialised with 65536 entries machine # [ 0.019554] Serial: AMBA PL011 UART driver machine # [ 0.025221] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 machine # [ 0.025380] printk: console [ttyAMA0] enabled machine # [ 0.151558] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.151570] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.151573] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages machine # [ 0.151576] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.151579] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.151582] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.151584] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages machine # [ 0.151587] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.152827] fbcon: Taking over console machine # [ 0.152837] ACPI: Interpreter disabled. machine # [ 0.153812] iommu: Default domain type: Translated machine # [ 0.153816] iommu: DMA domain TLB invalidation policy: strict mode machine # [ 0.164668] SCSI subsystem initialized machine # [ 0.164874] usbcore: registered new interface driver usbfs machine # [ 0.164902] usbcore: registered new interface driver hub machine # [ 0.164917] usbcore: registered new device driver usb machine # [ 0.165232] pps_core: LinuxPPS API ver. 1 registered machine # [ 0.165236] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti machine # [ 0.165244] PTP clock support registered machine # [ 0.165295] EDAC MC: Ver: 3.0.0 machine # [ 0.165451] scmi_core: SCMI protocol bus registered machine # [ 0.174267] FPGA manager framework machine # [ 0.174787] vgaarb: loaded machine # [ 0.175078] clocksource: Switched to clocksource arch_sys_counter machine # [ 0.180430] VFS: Disk quotas dquot_6.6.0 machine # [ 0.180454] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.182763] netfs: FS-Cache loaded machine # [ 0.182885] pnp: PnP ACPI: disabled machine # [ 0.188188] NET: Registered PF_INET protocol family machine # [ 0.188341] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.218819] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.218859] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.218884] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.218925] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.218998] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.224068] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.224129] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.224177] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.224271] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.224326] NET: Registered PF_XDP protocol family machine # [ 0.224345] PCI: CLS 0 bytes, default 64 machine # [ 0.224573] Trying to unpack rootfs image as initramfs... machine # [ 0.239113] kvm [1]: HYP mode not available machine # [ 0.351643] Initialise system trusted keyrings machine # [ 0.351779] workingset: timestamp_bits=42 max_order=18 bucket_order=0 machine # [ 0.352201] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.352248] 9p: Installing v9fs 9p2000 file system support machine # [ 0.380317] Key type asymmetric registered machine # [ 0.380335] Asymmetric key parser 'x509' registered machine # [ 0.380409] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.380562] io scheduler mq-deadline registered machine # [ 0.380567] io scheduler kyber registered machine # [ 0.393285] pl061_gpio 9030000.pl061: PL061 GPIO chip registered machine # [ 0.393871] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.394229] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: machine # [ 0.394242] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 machine # [ 0.394252] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 machine # [ 0.394257] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 machine # [ 0.394279] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits machine # [ 0.394297] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.394371] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.394378] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.394383] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.394386] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.394389] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] machine # [ 0.394457] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.394923] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.415197] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.415220] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.415248] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.415260] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.415792] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.415980] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.415992] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.416017] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.416506] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.416688] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] machine # [ 0.416700] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.416724] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.417181] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.417364] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.417375] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.417399] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.417410] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.417856] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.418037] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.418062] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.418519] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.418705] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.418730] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.437761] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.437938] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] machine # [ 0.438178] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.438359] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.438383] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.438821] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.439005] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.439030] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.451595] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.451801] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.451827] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.452323] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.452617] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.452628] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.452653] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.453113] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.453295] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.453306] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.453331] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.453931] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.453937] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.453941] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.453985] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.454028] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned machine # [ 0.454072] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned machine # [ 0.454115] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.454158] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.454202] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.454244] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned machine # [ 0.454293] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned machine # [ 0.454334] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned machine # [ 0.454394] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.454485] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.454502] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.454519] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned machine # [ 0.454535] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned machine # [ 0.454552] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned machine # [ 0.454569] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned machine # [ 0.454587] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned machine # [ 0.454605] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned machine # [ 0.454626] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned machine # [ 0.454643] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned machine # [ 0.454664] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned machine # [ 0.454682] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned machine # [ 0.454699] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned machine # [ 0.454716] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned machine # [ 0.454732] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned machine # [ 0.454749] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned machine # [ 0.454766] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned machine # [ 0.454784] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned machine # [ 0.454806] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] machine # [ 0.454811] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] machine # [ 0.454814] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] machine # [ 0.463913] pci 0000:00:07.0: enabling device (0000 -> 0002) machine # [ 0.522083] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) machine # [ 0.525573] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) machine # [ 0.536382] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) machine # [ 0.538300] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) machine # [ 0.542017] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) machine # [ 0.552794] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) machine # [ 0.554681] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.558078] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) machine # [ 0.568322] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.570346] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) machine # [ 0.574390] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) machine # [ 0.587516] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.589257] msm_serial: driver initialized machine # [ 0.589413] SuperH (H)SCI(F) driver initialized machine # [ 0.589470] STM32 USART driver initialized machine # [ 0.625924] loop: module loaded machine # [ 0.626124] virtio_blk virtio2: 1/0/0 default/read/poll queues machine # [ 0.626987] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 0.639223] megasas: 07.734.00.00-rc1 machine # [ 0.640230] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] machine # [ 0.642585] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.642636] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.644348] Using buffer write method machine # [ 0.644383] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] machine # [ 0.646298] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.646312] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.648389] Using buffer write method machine # [ 0.648404] Concatenating MTD devices: machine # [ 0.648407] (0): "0.flash" machine # [ 0.648409] (1): "0.flash" machine # [ 0.648411] into device "0.flash" machine # [ 0.939006] Freeing initrd memory: 26380K machine # [ 0.945333] tun: Universal TUN/TAP device driver, 1.6 machine # [ 0.949424] thunder_xcv, ver 1.0 machine # [ 0.949454] thunder_bgx, ver 1.0 machine # [ 0.949472] nicpf, ver 1.0 machine # [ 0.950048] e1000: Intel(R) PRO/1000 Network Driver machine # [ 0.950052] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 0.950079] e1000e: Intel(R) PRO/1000 Network Driver machine # [ 0.950087] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. machine # [ 0.950110] igb: Intel(R) Gigabit Ethernet Network Driver machine # [ 0.950114] igb: Copyright (c) 2007-2014 Intel Corporation. machine # [ 0.950133] igbvf: Intel(R) Gigabit Virtual Function Network Driver machine # [ 0.950136] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 0.950274] sky2: driver version 1.30 machine # [ 0.959968] ehci-pci 0000:00:07.0: EHCI Host Controller machine # [ 0.959990] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 machine # [ 0.960197] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 machine # [ 0.960662] usbcore: registered new interface driver usb-storage machine # [ 0.960710] usbcore: registered new interface driver usbserial_generic machine # [ 0.960721] usbserial: USB Serial support registered for generic machine # [ 0.961378] hv_vmbus: registering driver hyperv_keyboard machine # [ 0.962291] rtc-pl031 9010000.pl031: registered as rtc0 machine # [ 0.962317] rtc-pl031 9010000.pl031: setting system clock to 2026-09-23T02:59:50 UTC (1790132390) machine # [ 0.962627] i2c_dev: i2c /dev entries driver machine # [ 0.971178] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 machine # [ 0.971455] hub 1-0:1.0: USB hub found machine # [ 0.971470] hub 1-0:1.0: 6 ports detected machine # [ 0.974363] sdhci: Secure Digital Host Controller Interface driver machine # [ 0.974368] sdhci: Copyright(c) Pierre Ossman machine # [ 0.974659] Synopsys Designware Multimedia Card Interface Driver machine # [ 0.975044] sdhci-pltfm: SDHCI platform and OF driver helper machine # [ 0.981304] hid: raw HID events driver (C) Jiri Kosina machine # [ 0.981534] usbcore: registered new interface driver usbhid machine # [ 0.981543] usbhid: USB HID core driver machine # [ 0.982742] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available machine # [ 0.984381] drop_monitor: Initializing network drop monitor service machine # [ 0.984573] NET: Registered PF_INET6 protocol family machine # [ 0.989561] Segment Routing with IPv6 machine # [ 0.989574] In-situ OAM (IOAM) with IPv6 machine # [ 0.989616] NET: Registered PF_PACKET protocol family machine # [ 0.989670] 9pnet: Installing 9P2000 support machine # [ 0.989710] Key type dns_resolver registered machine # [ 0.998507] registered taskstats version 1 machine # [ 0.998676] Loading compiled-in X.509 certificates machine # [ 1.007620] Demotion targets for Node 0: null machine # [ 1.007726] Key type .fscrypt registered machine # [ 1.007733] Key type fscrypt-provisioning registered machine # [ 1.007837] ima: No TPM chip found, activating TPM-bypass! machine # [ 1.007854] ima: Allocated hash algorithm: sha1 machine # [ 1.007876] ima: No architecture policies found machine # [ 1.008621] input: gpio-keys as /devices/platform/gpio-keys/input/input0 machine # [ 1.032388] clk: Disabling unused clocks machine # [ 1.032407] PM: genpd: Disabling unused power domains machine # [ 1.036768] Freeing unused kernel memory: 4736K machine # [ 1.036972] Run /init as init process machine # [ 1.054868] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.219180] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 1.369325] 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.390370] systemd[1]: systemd 263-ef3e0ff 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.390421] systemd[1]: Detected virtualization qemu. machine # [ 1.390549] systemd[1]: Detected architecture arm64. machine # [ 1.390566] systemd[1]: Running in initrd. machine # [ 1.408560] systemd[1]: Initializing machine ID from random generator. machine # [ 1.408866] systemd[1]: Hostname set to . machine # [ 1.463000] 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.522325] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.579410] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.583173] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.587473] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.587667] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.587694] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.587717] systemd[1]: Reached target Path Units. machine # [ 1.587729] systemd[1]: Reached target Slice Units. machine # [ 1.587743] systemd[1]: Reached target Swaps. machine # [ 1.587756] systemd[1]: Reached target Timer Units. machine # [ 1.587897] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.588051] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.588206] systemd[1]: Listening on Journal Sockets. machine # [ 1.588283] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.588297] systemd[1]: Reached target Socket Units. machine # [ 1.589722] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.589795] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.606204] systemd[1]: Mounting Kernel Configuration File System... machine # [ 1.615409] systemd[1]: Starting Journal Service... machine # [ 1.651480] systemd[1]: Starting Load Kernel Modules... machine # [ 1.651630] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.678089] systemd-journald[71]: Collecting audit messages is disabled. machine # [ 1.679348] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.682759] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.691997] systemd[1]: Mounted Kernel Configuration File System. machine # [ 1.708416] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.709127] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.725937] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.751751] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 machine # [ 1.751998] [drm] features: -virgl +edid -resource_blob -host_visible machine # [ 1.752001] [drm] features: -context_init machine # [ 1.752745] [drm] number of scanouts: 1 machine # [ 1.752757] [drm] number of cap sets: 0 machine # [ 1.753178] 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.753362] 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 # [ 1.761923] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic machine # [ 1.761937] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 machine # [ 1.783916] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.791535] Console: switching to colour frame buffer device 160x50 machine # [ 1.798207] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.816354] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device machine # [ 1.832685] systemd[1]: Finished Load Kernel Modules. machine # [ 1.834469] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.851691] systemd-modules-load[72]: Inserted module 'dm_mod' machine # [ 1.863343] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.863953] systemd[1]: Started Journal Service. machine # [ 1.854344] systemd-modules-load[72]: Module 'virtio_balloon' is built in machine # [ 1.868410] systemd-modules-load[72]: Module 'virtio_console' is built in machine # [ 1.872587] systemd-modules-load[72]: Inserted module 'virtio_gpu' machine # [ 1.873798] systemd-modules-load[72]: Module 'virtio_rng' is built in machine # [ 1.876335] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.886827] systemd[1]: Reached target Local File Systems. machine # [ 1.892455] systemd[1]: Starting Create System Files and Directories... machine # [ 1.900578] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.908672] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.911365] systemd[1]: Finished Create System Files and Directories. machine # [ 1.973499] systemd-udevd[81]: Using default interface naming scheme 'v261'. machine # [ 2.002031] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 2.084690] systemd[1]: Starting Virtual Console Setup... machine # [ 2.165892] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.171166] systemd[1]: Finished Virtual Console Setup. machine # [ 2.755319] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.757071] systemd[1]: Reached target System Initialization. machine # [ 2.757983] systemd[1]: Reached target Basic System. machine # [ 2.926282] (udev-worker)[97]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 2.932339] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line. machine # [ 2.985576] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 2.987997] systemd[1]: Reached target Initrd Root Device. machine # [ 2.992329] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 3.048492] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 3.056076] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 3.064366] systemd[1]: Mounting /sysroot... machine # [ 3.114800] EXT4-fs (vda): mounted filesystem ce7a81f5-595c-41cb-88d7-20c367377512 r/w with ordered data mode. Quota mode: none. machine # [ 3.109378] systemd[1]: Mounted /sysroot. machine # [ 3.111117] systemd[1]: Reached target Initrd Root File System. machine # [ 3.115081] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 3.144251] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 3.151675] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 3.156275] systemd[1]: Reloading... machine # [ 3.355172] systemd[1]: Reloading finished in 200 ms. machine # [ 3.386173] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace... machine # [ 3.392542] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace... machine # [ 3.397301] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 3.400923] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 3.401964] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 3.669152] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.688128] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 3.695623] systemd[1]: Mounting /sysroot/run... machine # [ 3.724121] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.768141] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.782334] fuse: init (API version 7.45) machine # [ 3.790057] virtiofs virtio6: discovered new tag: nix-store machine # [ 3.790879] virtiofs virtio6: virtio_fs_setup_dax: No cache capability machine # [ 3.806567] virtiofs virtio7: discovered new tag: shared machine # [ 3.814325] virtiofs virtio7: virtio_fs_setup_dax: No cache capability machine # [ 3.819939] virtiofs virtio8: discovered new tag: xchg machine # [ 3.820747] virtiofs virtio8: virtio_fs_setup_dax: No cache capability machine # [ 3.836167] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.842719] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.844972] systemd[1]: Mounted /sysroot/run. machine # [ 3.850413] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.871269] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.874390] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.898837] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.900366] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.320876] (udev-worker)[91]: mtd0ro: Failed to find and pin callout binary "/nix/store/2lckkskw40in5vmpcihl3b4rygx8df89-systemd-263-ef3e0ff/lib/udev/mtd_probe": No such file or directory machine # [ 4.330663] (udev-worker)[91]: 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.361818] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.364408] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.366807] systemd[1]: Stopping Virtual Console Setup... machine # [ 4.372370] systemd[1]: Starting Virtual Console Setup... machine # [ 4.373666] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.375094] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.385244] systemd[1]: Starting Virtual Console Setup... machine # [ 4.414680] systemd-vconsole-setup[152]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 4.418341] systemd[1]: Finished Virtual Console Setup. machine # [ 4.673122] systemd[1]: Mounting /sysroot/nix/store... machine # [ 4.742258] systemd[1]: Mounted /sysroot/nix/store. machine # [ 4.747633] systemd[1]: Reached target Initrd File Systems. machine # [ 4.752553] systemd[1]: Starting Find NixOS closure... machine # [ 4.761124] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 4.821172] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 4.824563] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. machine # [ 4.832874] systemd[1]: Finished Find NixOS closure. machine # [ 4.836219] systemd[1]: Reached target Initrd Default Target. machine # [ 4.838061] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 4.867975] systemd[1]: Stopped target Initrd Default Target. machine # [ 4.872727] systemd[1]: Stopped target Basic System. machine # [ 4.873742] systemd[1]: Stopped target Initrd Root Device. machine # [ 4.874722] systemd[1]: Stopped target Path Units. machine # [ 4.876757] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 4.880213] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 4.882348] systemd[1]: Stopped target Slice Units. machine # [ 4.888158] systemd[1]: Stopped target Socket Units. machine # [ 4.889246] systemd[1]: Stopped target System Initialization. machine # [ 4.890283] systemd[1]: Stopped target Swaps. machine # [ 4.891069] systemd[1]: Stopped target Timer Units. machine # [ 4.891899] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 4.895267] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 4.905372] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 4.906611] systemd[1]: Stopped Find NixOS closure. machine # [ 4.907499] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.916229] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 4.917257] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.917988] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 4.918956] systemd[1]: Stopped Load Kernel Modules. machine # [ 4.919646] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 4.928158] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 4.929368] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 4.930398] systemd[1]: Stopped Create System Files and Directories. machine # [ 4.931290] systemd[1]: Stopped target Local File Systems. machine # [ 4.937556] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 4.940267] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 4.944605] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 4.945496] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 4.946526] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.947628] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.948596] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 4.949545] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 4.950465] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 4.951468] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.952401] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 4.953353] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 4.954356] systemd[1]: systemd-udevd.service: Consumed 1.475s CPU time over 3.046s wall clock time, 22.3M memory peak. machine # [ 4.955776] systemd[1]: Starting Cleanup udev Database... machine # [ 4.960281] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 4.961443] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 4.964470] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 4.968271] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 4.969399] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 4.972160] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 5.002432] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 5.004529] systemd[1]: Finished Cleanup udev Database. machine # [ 5.006927] systemd[1]: Reached target Switch Root. machine # [ 5.008813] systemd[1]: Starting NixOS Activation... machine # [ 5.094413] initrd-nixos-activation-start[175]: booting system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test machine # [ 5.127516] initrd-nixos-activation-start[175]: running activation script... machine # [ 5.356642] initrd-nixos-activation-start[198]: setting up /etc... machine # [ 5.482978] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 5.486254] systemd[1]: Finished NixOS Activation. machine # [ 5.487618] systemd[1]: Starting Switch Root... machine # [ 5.513818] systemd[1]: Switching root. machine # [ 5.697306] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). machine # [ 6.222208] systemd[1]: systemd 263-ef3e0ff running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) machine # [ 6.222318] systemd[1]: Detected virtualization qemu. machine # [ 6.222426] systemd[1]: Detected architecture arm64. machine # [ 6.222667] systemd[1]: Detected first boot. machine # [ 6.243723] systemd[1]: Initializing machine ID from random generator. machine # [ 6.417006] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 6.582139] systemd[1]: Applying preset policy. machine # [ 6.814986] systemd[1]: Populated /etc with preset unit settings. machine # [ 7.038642] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 7.041812] systemd[1]: Stopped initrd-switch-root.service. machine # [ 7.045411] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 7.049231] systemd[1]: Created slice Slice /system/getty. machine # [ 7.050670] systemd[1]: Created slice User and Session Slice. machine # [ 7.053741] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 7.054348] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 7.058036] systemd[1]: Expecting device /dev/hvc0... machine # [ 7.058492] systemd[1]: Expecting device /dev/ttyAMA0... machine # [ 7.061606] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 7.062066] systemd[1]: Stopped target initrd-fs.target. machine # [ 7.062930] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 7.066722] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 7.068897] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 7.069352] systemd[1]: Reached target Path Units. machine # [ 7.070200] systemd[1]: Reached target Remote File Systems. machine # [ 7.071078] systemd[1]: Reached target Slice Units. machine # [ 7.075446] systemd[1]: Reached target Swaps. machine # [ 7.077340] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 7.081121] systemd[1]: Listening on Process Core Dump Socket. machine # [ 7.082642] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 7.086518] systemd[1]: Listening on Factory Reset Management. machine # [ 7.088604] systemd[1]: Listening on Hostname Service Socket. machine # [ 7.092681] systemd[1]: Starting Journal Log Access Socket... machine # [ 7.094221] systemd[1]: Listening on Journal Audit Socket. machine # [ 7.098411] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 7.101798] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 7.102217] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.102493] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 7.112088] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 7.112770] systemd[1]: Listening on udev Varlink Socket. machine # [ 7.117679] systemd[1]: Mounting Huge Pages File System... machine # [ 7.122723] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 7.136203] systemd[1]: Mounting Kernel Debug File System... machine # [ 7.143915] systemd[1]: Mounting Kernel Trace File System... machine # [ 7.167282] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 7.172161] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 7.187805] systemd[1]: Mounting Kernel Configuration File System... machine # [ 7.188894] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 7.198773] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 7.201867] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 7.219951] systemd[1]: Mounting FUSE Control File System... machine # [ 7.221497] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 7.255235] systemd[1]: Starting Journal Service... machine # [ 7.277386] systemd[1]: Starting Load Kernel Modules... machine # [ 7.299392] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 7.319904] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 7.320337] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.330509] systemd[1]: Starting Coldplug All udev Devices... machine # [ 7.340103] systemd-journald[269]: Collecting audit messages is enabled. machine # [ 7.333708] systemd[1]: Queued start job for default target Multi-User System. machine # [ 7.350464] systemd[1]: Started Journal Service. machine # [ 7.339203] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 7.342012] systemd-modules-load[270]: Module 'atkbd' is built in machine # [ 7.349598] systemd[1]: Listening on Journal Log Access Socket. machine # [ 7.350630] systemd[1]: Mounted Huge Pages File System. machine # [ 7.351395] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 7.359770] systemd[1]: Mounted Kernel Debug File System. machine # [ 7.365205] systemd[1]: Mounted Kernel Trace File System. machine # [ 7.366195] systemd-modules-load[270]: Module 'loop' is built in machine # [ 7.367059] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 7.374288] systemd[1]: Finished Load Kernel Modules. machine # [ 7.408312] systemd[1]: Starting Firewall... machine # [ 7.416235] systemd[1]: Starting Apply Kernel Variables... machine # [ 7.424097] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 7.434983] systemd[1]: Mounted Kernel Configuration File System. machine # [ 7.445421] systemd-oomd[272]: No swap; memory pressure usage will be degraded machine # [ 7.465100] EXT4-fs (vda): re-mounted ce7a81f5-595c-41cb-88d7-20c367377512. machine # [ 7.461631] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 7.477941] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 7.492670] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 7.506290] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 7.517372] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 7.524661] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 7.525690] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 7.526981] systemd[1]: Mounted FUSE Control File System. machine # [ 7.623241] systemd-journald[269]: Received client request to flush runtime journal. machine # [ 7.668463] systemd[1]: Finished Apply Kernel Variables. machine # [ 7.669460] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 7.670245] systemd[1]: Reached target First Boot Complete. machine # [ 7.678359] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 7.729006] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 7.737365] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 8.032919] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 8.035256] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 8.042971] systemd[1]: Mounting /run/wrappers... machine # [ 8.051525] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 8.141291] systemd[1]: Mounted /run/wrappers. machine # [ 8.142149] systemd[1]: Reached target Local File Systems. machine # [ 8.150281] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 8.158323] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 8.159313] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 8.168328] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 8.182657] systemd[1]: Starting Create System Files and Directories... machine # [ 8.253720] systemd-udevd[309]: Using default interface naming scheme 'v261'. machine # [ 8.385278] systemd[1]: Finished Create System Files and Directories. machine # [ 8.405503] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 8.412321] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 8.432186] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 8.516364] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 8.597301] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 8.633861] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 8.646615] systemd[1]: Starting Update is Completed... machine # [ 8.763078] systemd[1]: Finished Update is Completed. machine # [ 8.829280] systemd[1]: Finished Coldplug All udev Devices. machine # [ 8.969419] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 9.005423] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 9.024796] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 9.103386] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 9.107763] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 9.111995] systemd[1]: Reached target System Initialization. machine # [ 9.115342] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 9.120543] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 9.121579] systemd[1]: Reached target Timer Units. machine # [ 9.122312] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 9.123206] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 9.134985] systemd[1]: Reached target Socket Units. machine # [ 9.135768] systemd[1]: Reached target Basic System. machine # [ 9.141392] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 9.142504] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 9.143414] systemd[1]: Starting Post-Boot Actions... machine # [ 9.162392] systemd[1]: Started Reset console on configuration changes. machine # [ 9.183782] systemd[1]: Starting resolvconf update... machine # [ 9.239445] systemd[1]: Starting D-Bus System Message Bus... machine # [ 9.347495] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 9.354597] nsncd[423]: Sep 23 02:59:58.899 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 9.369212] systemd[1]: Finished Post-Boot Actions. machine # [ 9.383935] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 9.385454] systemd[1]: Reached target User and Group Name Lookups. machine # [ 9.406559] systemd[1]: Starting User Login Management... machine # [ 9.417879] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 9.448443] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 9.510986] dbus-broker-launch[428]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 9.522909] dbus-broker-launch[428]: NSS returned no entry for 'systemd-timesync' machine # [ 9.530486] dbus-broker-launch[428]: Invalid user-name in /nix/store/w0wya9dwm0y8jgbs16l05by7m2pj9b4y-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 9.548363] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. machine # [ 9.560984] systemd[1]: Started backdoor.service. machine # [ 9.607022] systemd[1]: Started D-Bus System Message Bus. machine # [ 9.684792] dbus-broker-launch[428]: Ready machine # [ 9.706673] systemd-logind[451]: New seat seat0. machine # [ 9.719010] systemd[1]: Started User Login Management. machine # [ 9.732363] systemd[1]: Starting linger-users.service... machine # connecting to host... machine # [ 9.797184] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 9.805061] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 9.806176] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 9.807074] systemd[1]: Stopping User and Group Name Lookups... machine # [ 9.807921] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 9.827051] systemd[1]: nscd.service: Deactivated successfully. machine # [ 9.830684] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine: Guest shell says: b'Spawning backdoor root shell...\n' machine: connected to guest root shell machine: (connecting took 10.18 seconds) machine: (finished: waiting for the VM to finish booting, in 10.72 seconds) machine # [ 9.893997] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 9.985539] systemd[1]: Created slice Slice /user/1001. machine # [ 9.998220] (udev-worker)[382]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 10.017384] systemd[1]: Starting User Runtime Directory /run/user/1001... machine # [ 10.042929] nsncd[517]: Sep 23 02:59:59.592 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 10.059719] (udev-worker)[382]: Network interface NamePolicy= disabled on kernel command line. machine # [ 10.070644] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 10.071817] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 10.079134] systemd[1]: Finished linger-users.service. machine # [ 10.203344] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 10.209744] systemd[1]: Reached target User and Group Name Lookups. machine # [ 10.224398] systemd[1]: Finished resolvconf update. machine # [ 10.245725] systemd[1]: Starting DHCP Client... machine # [ 10.355586] systemd[1]: Finished User Runtime Directory /run/user/1001. machine # [ 10.411762] systemd[1]: Starting User Manager for UID 1001... machine # [ 10.496680] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 10.588663] systemd-logind[451]: Watching system buttons on /dev/input/event0 (gpio-keys) machine # [ 10.636710] (systemd)[581]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 10.669021] systemd-logind[451]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'. machine # [ 10.754973] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 10.776169] mousedev: PS/2 mouse device common for all mice machine # [ 11.049266] systemd-logind[451]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 11.116575] systemd[581]: Queued start job for default target Main User Target. machine # [ 11.117925] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 11.119440] systemd[581]: Created slice User Application Slice. machine # [ 11.224557] systemd[581]: Started Daily Cleanup of User's Temporary Directories. machine # [ 11.225860] systemd[581]: Reached target Paths. machine # [ 11.264703] systemd[581]: Reached target Timers. machine # [ 11.277078] systemd[581]: Starting D-Bus User Message Bus Socket... machine # [ 11.284607] systemd[581]: Starting Create User Files and Directories... machine # [ 11.285749] systemd[1]: Finished Firewall. machine # [ 11.286410] systemd[1]: Reached target Preparation for Network. machine # [ 11.287292] systemd[1]: Starting Address configuration of eth1... machine # [ 11.323980] systemd[1]: Starting Extra networking commands.... machine # [ 11.335691] systemd[581]: Finished Create User Files and Directories. machine # [ 11.347811] systemd[581]: Listening on D-Bus User Message Bus Socket. machine # [ 11.355736] systemd[581]: Reached target Sockets. machine # [ 11.357202] systemd[581]: Reached target Basic System. machine # [ 11.357982] systemd[1]: Started User Manager for UID 1001. machine # [ 11.358780] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 11.359734] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 11.360746] systemd[581]: Reached target Main User Target. machine # [ 11.361543] systemd[581]: Startup finished in 601ms. machine # [ 11.438025] dhcpcd[587]: dhcpcd-10.3.2 starting machine # [ 11.450173] dhcpcd[616]: dev: loaded udev machine # [ 11.505876] 8021q: 802.1Q VLAN Support v1.8 machine # [ 11.571908] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 11.618543] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 11.645496] network-addresses-eth1-start[604]: adding address 192.168.1.1/24... done machine # [ 11.662095] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 11.662683] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 11.667317] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 11.667649] cfg80211: failed to load regulatory.db machine # [ 11.744273] network-addresses-eth1-start[604]: adding address 2001:db8:1::1/64... done machine # [ 11.858229] systemd[1]: Finished Address configuration of eth1. machine # [ 12.031526] dhcpcd[666]: /nix/store/1ki7bgdr0niilkg9jbkpcdaggbv0kb8a-openresolv-3.17.4/sbin/.resolvconf-wrapped: line 1250: kill: (618) - Operation not permitted machine # [ 12.059432] dhcpcd[666]: .resolvconf-wrapped: clearing stale lock pid 618 machine # [ 12.285671] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 12.276653] dhcpcd[616]: eth0: waiting for carrier machine # [ 12.277542] dhcpcd[616]: eth0: carrier acquired machine # [ 12.349337] dhcpcd[616]: DUID 00:01:00:01:32:45:fd:31:52:54:00:12:34:56 machine # [ 12.350448] dhcpcd[616]: eth0: IAID 00:12:34:56 machine # [ 12.351091] dhcpcd[616]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 12.403556] systemd[1]: Finished Extra networking commands.. machine # [ 12.412367] systemd[1]: Reached target Network. machine # [ 12.413321] systemd[1]: Starting Permit User Sessions... machine # [ 12.506164] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 machine # [ 12.616533] dhcpcd[616]: eth0: soliciting a DHCP lease machine # [ 12.637382] dhcpcd[616]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 12.638437] dhcpcd[616]: eth0: probing address 10.0.2.15/24 machine # [ 12.680163] systemd[1]: Finished Permit User Sessions. machine # [ 12.696776] systemd[1]: Started Getty on tty1. machine # [ 12.697623] systemd[1]: Reached target Login Prompts. machine # [ 12.948473] systemd[1]: Starting Virtual Console Setup... machine # [ 12.978084] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 13.026756] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 13.040637] systemd[1]: Stopped Virtual Console Setup. machine # [ 13.041509] systemd[1]: Starting Virtual Console Setup... machine # [ 13.042598] systemd-logind[451]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 13.188864] systemd-vconsole-setup[717]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 13.196111] systemd[1]: Finished Virtual Console Setup. machine # [ 14.759843] dhcpcd[616]: eth0: soliciting an IPv6 router machine # [ 14.763821] dhcpcd[616]: eth0: Router Advertisement from fe80::2 machine # [ 14.766942] dhcpcd[616]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 14.770036] dhcpcd[616]: eth0: adding route to fec0::/64 machine # [ 14.772497] dhcpcd[616]: eth0: adding default route via fe80::2 machine # [ 18.241007] dhcpcd[616]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 18.246035] dhcpcd[616]: eth0: adding route to 10.0.2.0/24 machine # [ 18.247857] dhcpcd[616]: eth0: adding default route via 10.0.2.2 machine # [ 18.399111] systemd[1]: Started DHCP Client. machine # [ 18.402018] systemd[1]: Reached target Multi-User System. machine # [ 18.402981] systemd[1]: Startup finished in 1.026s (kernel) + 4.752s (initrd) + 12.622s (userspace) = 18.401s. machine: (finished: waiting for unit multi-user.target, in 19.50 seconds) machine: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test machine # Checking switch inhibitors... done machine # [ 18.963425] nixos[830]: switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test machine # [ 18.966785] systemd[1]: Stopped target Local File Systems. machine # activating the configuration... machine # [ 18.969953] systemd[1]: Stopped target Remote File Systems. machine # setting up /etc... machine # [ 19.309002] systemd[1]: Reload requested from client PID 830 ('.switch-to-conf') (unit backdoor.service)... machine # [ 19.311001] systemd[1]: Reloading... machine # [ 19.676195] systemd[1]: Reloading finished in 363 ms. machine # reloading user units for usertest... machine # [ 19.730062] systemd[581]: Created slice User Core Session Slice. machine # [ 19.733696] systemd[581]: Starting D-Bus User Message Bus... machine # [ 19.771083] dbus-broker-launch[900]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/w0wya9dwm0y8jgbs16l05by7m2pj9b4y-system-path/share/dbus-1/services/org.freedesktop.import1.service' machine # [ 19.774820] dbus-broker-launch[900]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/w0wya9dwm0y8jgbs16l05by7m2pj9b4y-system-path/share/dbus-1/services/org.freedesktop.machine1.service' machine # [ 19.778096] dbus-broker-launch[900]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/w0wya9dwm0y8jgbs16l05by7m2pj9b4y-system-path/share/dbus-1/services/org.freedesktop.portable1.service' machine # [ 19.781124] dbus-broker-launch[900]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/w0wya9dwm0y8jgbs16l05by7m2pj9b4y-system-path/share/dbus-1/services/org.freedesktop.systemd1.service' machine # [ 19.786466] dbus-broker-launch[900]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored machine # [ 19.789994] dbus-broker-launch[900]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored machine # [ 19.791725] systemd[581]: Started D-Bus User Message Bus. machine # [ 19.796423] dbus-broker-launch[900]: Ready machine # [ 19.846827] systemd[581]: Reexecution requested from client PID 899 ('switch-to-confi')... machine # [ 19.850760] systemd[581]: Reexecuting. machine # [ 19.903451] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 20.029297] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 20.036660] systemd[581]: Reload requested from client PID 899 ('switch-to-confi')... machine # [ 20.040132] systemd[581]: Reloading... machine # [ 20.161468] systemd[581]: Reloading finished in 121 ms. machine # [ 20.176246] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 20.189250] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 20.191879] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 20.194404] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 20.230868] systemd[581]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 20.243824] systemd[1]: Starting Re-setup tmpfiles on a system that is already running.... machine # [ 20.335320] systemd[1]: Finished Re-setup tmpfiles on a system that is already running.. machine # [ 20.337914] systemd[1]: Reached target Reactivate sysinit units. machine # [ 20.341770] systemd[1]: Reached target Local File Systems. machine # [ 20.345792] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 20.350300] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 20.351256] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 20.360659] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 20.363069] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 20.372967] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 20.374386] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 20.375657] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 20.377144] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 20.378449] systemd[1]: Reached target Remote File Systems. machine # [ 20.550911] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 20.552813] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 20.558712] systemd[1]: Starting linger-users.service... machine # [ 20.607412] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 20.609393] systemd[1]: Finished linger-users.service. machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service machine # [ 20.875519] nixos[830]: finished switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test machine: (finished: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test, in 2.24 seconds) machine: must succeed: rm /etc/NIXOS machine: (finished: must succeed: rm /etc/NIXOS, in 0.05 seconds) machine: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/s9d0p34vf3pnv7gnbgyc62zk4szw7r36-nixos-system-other-test/bin/switch-to-configuration test machine # this should succeed (config: /nix/store/s9d0p34vf3pnv7gnbgyc62zk4szw7r36-nixos-system-other-test, action: test) machine # Checking switch inhibitors... done machine # [ 21.227374] nixos[977]: switching to system configuration /nix/store/s9d0p34vf3pnv7gnbgyc62zk4szw7r36-nixos-system-other-test machine # activating the configuration... machine # [ 21.233546] systemd[1]: Stopped target Remote File Systems. machine # [ 21.234485] systemd[1]: Stopped target Local File Systems. machine # removing user ‘usertest’ machine # setting up /etc... machine # [ 21.587449] systemd[1]: Reload requested from client PID 977 ('.switch-to-conf') (unit backdoor.service)... machine # [ 21.589943] systemd[1]: Reloading... machine # [ 21.975159] systemd[1]: Reloading finished in 383 ms. machine # reloading user units for usertest... machine # [ 22.076557] systemd[581]: Reexecution requested from client PID 1049 ('switch-to-confi')... machine # [ 22.079999] systemd[581]: Reexecuting. machine # [ 22.140086] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 22.272640] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 22.280519] systemd[581]: Reload requested from client PID 1049 ('switch-to-confi')... machine # [ 22.281939] systemd[581]: Reloading... machine # [ 22.412468] systemd[581]: Reloading finished in 129 ms. machine # [ 22.428295] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 22.441898] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 22.442926] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 22.445079] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 22.484709] systemd[581]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 22.490655] systemd[1]: Stopped target Reactivate sysinit units. machine # reloading the following units: dbus-broker.service machine # [ 22.495250] systemd[1]: Stopping Reactivate sysinit units... machine # [ 22.498322] systemd[1]: Reached target Reactivate sysinit units. machine # [ 22.499573] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 22.501161] dbus-broker-launch[428]: Caught SIGHUP, trigger reload. machine # [ 22.508177] dbus-broker-launch[428]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 22.510748] dbus-broker-launch[428]: NSS returned no entry for 'systemd-timesync' machine # [ 22.511945] dbus-broker-launch[428]: Invalid user-name in /nix/store/q6wjsw0nyp2xvbk5q7qp4h4kymalq12s-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 22.539873] systemd[1]: Reloaded D-Bus System Message Bus. machine # restarting the following units: network-addresses-eth1.service machine # [ 22.547958] systemd[1]: Stopping Address configuration of eth1... machine # [ 22.585051] network-addresses-eth1-pre-stop[1061]: deleting address 192.168.1.1/24... done machine # [ 22.593509] network-addresses-eth1-pre-stop[1061]: deleting address 2001:db8:1::1/64... done machine # [ 22.602034] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 22.604137] systemd[1]: Stopped Address configuration of eth1. machine # [ 22.609448] systemd[1]: Starting Address configuration of eth1... machine # [ 22.658302] network-addresses-eth1-start[1066]: adding address 192.168.1.2/24... done machine # [ 22.666727] network-addresses-eth1-start[1066]: adding address 2001:db8:1::2/64... done machine # [ 22.677103] systemd[1]: Finished Address configuration of eth1. machine # [ 22.684621] systemd[1]: Reached target Remote File Systems. machine # [ 22.687557] systemd[1]: Reached target Local File Systems. machine # [ 22.691773] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 22.696207] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 22.697285] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 22.698552] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 22.708882] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 22.710606] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.711881] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.720176] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 22.721559] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 22.983769] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 22.988615] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 22.991853] systemd[1]: Starting linger-users.service... machine # [ 23.027720] linger-users-start[1138]: id: ‘usertest’: no such user machine # [ 23.030061] linger-users-start[1136]: Removing linger for missing user usertest machine # [ 23.038270] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 23.041253] systemd[1]: Finished linger-users.service. machine # [ 23.298844] nixos[977]: finished switching to system configuration /nix/store/s9d0p34vf3pnv7gnbgyc62zk4szw7r36-nixos-system-other-test machine: (finished: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/s9d0p34vf3pnv7gnbgyc62zk4szw7r36-nixos-system-other-test/bin/switch-to-configuration test, in 2.37 seconds) machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 23.578620] nixos[1144]: switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test machine # [ 23.582039] systemd[1]: Stopped target Remote File Systems. machine # [ 23.584876] systemd[1]: Stopped target Local File Systems. machine # [ 23.885121] systemd[1]: Reload requested from client PID 1144 ('.switch-to-conf') (unit backdoor.service)... machine # [ 23.887135] systemd[1]: Reloading... machine # [ 24.237832] systemd[1]: Reloading finished in 349 ms. machine # [ 24.332739] systemd[581]: Reexecution requested from client PID 1213 ('switch-to-confi')... machine # [ 24.337027] systemd[581]: Reexecuting. machine # [ 24.391634] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 24.518688] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 24.525939] systemd[581]: Reload requested from client PID 1213 ('switch-to-confi')... machine # [ 24.528691] systemd[581]: Reloading... machine # [ 24.648621] systemd[581]: Reloading finished in 118 ms. machine # [ 24.664194] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 24.674263] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 24.676992] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 24.679298] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 24.714534] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 24.721318] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 24.722647] systemd[1]: Stopping Reactivate sysinit units... machine # [ 24.723489] systemd[1]: Reached target Reactivate sysinit units. machine # [ 24.728774] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 24.730501] dbus-broker-launch[428]: Caught SIGHUP, trigger reload. machine # [ 24.734457] dbus-broker-launch[428]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 24.736754] dbus-broker-launch[428]: NSS returned no entry for 'systemd-timesync' machine # [ 24.737852] dbus-broker-launch[428]: Invalid user-name in /nix/store/w0wya9dwm0y8jgbs16l05by7m2pj9b4y-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 24.765229] systemd[1]: Reloaded D-Bus System Message Bus. machine # [ 24.772002] systemd[1]: Stopping Address configuration of eth1... machine # [ 24.813388] network-addresses-eth1-pre-stop[1225]: deleting address 192.168.1.2/24... done machine # [ 24.825435] network-addresses-eth1-pre-stop[1225]: deleting address 2001:db8:1::2/64... done machine # [ 24.837514] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 24.840842] systemd[1]: Stopped Address configuration of eth1. machine # [ 24.846092] systemd[1]: Starting Address configuration of eth1... machine # [ 24.912715] network-addresses-eth1-start[1230]: adding address 192.168.1.1/24... done machine # [ 24.920383] network-addresses-eth1-start[1230]: adding address 2001:db8:1::1/64... done machine # [ 24.930718] systemd[1]: Finished Address configuration of eth1. machine # [ 24.937942] systemd[1]: Reached target Local File Systems. machine # [ 24.943516] systemd[1]: Reached target Remote File Systems. machine # [ 24.948107] systemd[1]: Starting linger-users.service... machine # [ 24.960136] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 24.972227] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 24.973472] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 24.974801] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 24.983733] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 24.988169] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.989630] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.990825] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.998945] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 25.027207] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 25.029349] systemd[1]: Finished linger-users.service. machine # [ 25.194505] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 25.196592] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 25.460400] nixos[1144]: finished switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.16 seconds) subtest: user services machine: waiting for unit user@1001.service machine: (finished: waiting for unit user@1001.service, in 0.10 seconds) machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 25.796103] nixos[1304]: switching to system configuration /nix/store/ydz9rnv2xwg2gpnvrbs6c3d2wn1s9s4w-nixos-system-machine-test machine # [ 25.798915] systemd[1]: Stopped target Local File Systems. machine # [ 25.801429] systemd[1]: Stopped target Remote File Systems. machine # [ 26.115544] systemd[1]: Reload requested from client PID 1304 ('.switch-to-conf') (unit backdoor.service)... machine # [ 26.117820] systemd[1]: Reloading... machine # [ 26.469569] systemd[1]: Reloading finished in 349 ms. machine # [ 26.561504] systemd[581]: Reexecution requested from client PID 1373 ('switch-to-confi')... machine # [ 26.565851] systemd[581]: Reexecuting. machine # [ 26.619189] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 26.742234] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 26.748868] systemd[581]: Reload requested from client PID 1373 ('switch-to-confi')... machine # [ 26.751432] systemd[581]: Reloading... machine # [ 26.873135] systemd[581]: Reloading finished in 120 ms. machine # [ 26.888193] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 26.899364] systemd[581]: Starting usertest.service... machine # [ 26.927401] systemd[581]: Finished usertest.service. machine # [ 26.930450] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 26.935128] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 26.938196] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 26.975702] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 26.980496] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 26.983488] systemd[1]: Stopping Reactivate sysinit units... machine # [ 26.985575] systemd[1]: Reached target Reactivate sysinit units. machine # [ 26.987709] systemd[1]: Reached target Remote File Systems. machine # [ 26.993425] systemd[1]: Starting linger-users.service... machine # [ 27.003804] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.006016] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 27.010504] systemd[1]: Reached target Local File Systems. machine # [ 27.013988] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 27.021265] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 27.022282] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 27.023553] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 27.036345] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 27.039363] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.043243] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 27.088830] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 27.093967] systemd[1]: Finished linger-users.service. machine # [ 27.267731] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 27.270019] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 27.531935] nixos[1304]: finished switching to system configuration /nix/store/ydz9rnv2xwg2gpnvrbs6c3d2wn1s9s4w-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserService/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 usertest.service machine # [ 27.623788] sudo[1443]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 27.645877] sudo[1443]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 27.661723] sudo[1443]: 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.12 seconds) machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 27.896823] nixos[1452]: switching to system configuration /nix/store/ydz9rnv2xwg2gpnvrbs6c3d2wn1s9s4w-nixos-system-machine-test machine # [ 27.899408] systemd[1]: Stopped target Local File Systems. machine # [ 27.902002] systemd[1]: Stopped target Remote File Systems. machine # [ 28.242704] systemd[1]: Reload requested from client PID 1452 ('.switch-to-conf') (unit backdoor.service)... machine # [ 28.244832] systemd[1]: Reloading... machine # [ 28.612676] systemd[1]: Reloading finished in 366 ms. machine # [ 28.712410] systemd[581]: Reexecution requested from client PID 1522 ('switch-to-confi')... machine # [ 28.713933] systemd[581]: Reexecuting. machine # [ 28.775711] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 28.906038] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 28.913156] systemd[581]: Reload requested from client PID 1522 ('switch-to-confi')... machine # [ 28.917249] systemd[581]: Reloading... machine # [ 29.035700] systemd[581]: Reloading finished in 119 ms. machine # [ 29.052336] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 29.062155] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 29.064848] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 29.067265] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 29.103118] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 29.107764] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 29.110902] systemd[1]: Stopping Reactivate sysinit units... machine # [ 29.111802] systemd[1]: Reached target Reactivate sysinit units. machine # [ 29.115755] systemd[1]: Reached target Local File Systems. machine # [ 29.121293] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 29.128572] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 29.129598] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 29.130943] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 29.144835] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 29.146590] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 29.147878] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 29.149760] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 29.151183] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 29.152649] systemd[1]: Reached target Remote File Systems. machine # [ 29.342430] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 29.347618] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 29.350059] systemd[1]: Starting linger-users.service... machine # [ 29.396723] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 29.399898] systemd[1]: Finished linger-users.service. machine # [ 29.660705] nixos[1452]: finished switching to system configuration /nix/store/ydz9rnv2xwg2gpnvrbs6c3d2wn1s9s4w-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.01 seconds) machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1 machine # [ 29.949075] nixos[1593]: switching to system configuration /nix/store/4i8x3ynh84c7jm18b0in82hwpxpfxqkb-nixos-system-machine-test machine # [ 29.951783] systemd[1]: Stopped target Remote File Systems. machine # [ 29.954662] systemd[1]: Stopped target Local File Systems. machine # [ 30.263823] systemd[1]: Reload requested from client PID 1593 ('.switch-to-conf') (unit backdoor.service)... machine # [ 30.266133] systemd[1]: Reloading... machine # [ 30.601372] systemd[1]: Reloading finished in 333 ms. machine # [ 30.695155] systemd[581]: Stopped usertest.service. machine # [ 30.698838] systemd[581]: Reexecution requested from client PID 1661 ('switch-to-confi')... machine # [ 30.701080] systemd[581]: Reexecuting. machine # [ 30.759298] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 30.875569] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 30.882666] systemd[581]: Reload requested from client PID 1661 ('switch-to-confi')... machine # [ 30.886558] systemd[581]: Reloading... machine # [ 30.999063] systemd[581]: Reloading finished in 113 ms. machine # [ 31.012186] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 31.021898] systemd[581]: Starting usertest.service... machine # [ 31.050136] systemd[581]: Finished usertest.service. machine # [ 31.053075] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 31.055279] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 31.057519] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 31.095600] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 31.099639] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 31.101512] systemd[1]: Stopping Reactivate sysinit units... machine # [ 31.102351] systemd[1]: Reached target Reactivate sysinit units. machine # [ 31.106358] systemd[1]: Reached target Local File Systems. machine # [ 31.113922] systemd[1]: Reached target Remote File Systems. machine # [ 31.117648] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 31.119045] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 31.120812] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 31.123261] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 31.128444] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 31.129793] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 31.131928] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 31.139966] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 31.142154] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 31.308484] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 31.312650] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 31.315684] systemd[1]: Starting linger-users.service... machine # [ 31.359776] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 31.362142] systemd[1]: Finished linger-users.service. machine # [ 31.622208] nixos[1593]: finished switching to system configuration /nix/store/4i8x3ynh84c7jm18b0in82hwpxpfxqkb-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 1.96 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 31.698354] sudo[1733]: 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.717677] sudo[1733]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 31.733423] sudo[1733]: 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/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 31.959790] nixos[1742]: switching to system configuration /nix/store/a8iaf9wvnin33cj060m3q3zzx4qmnz9b-nixos-system-machine-test machine # [ 31.963265] systemd[1]: Stopped target Remote File Systems. machine # [ 31.965876] systemd[1]: Stopped target Local File Systems. machine # [ 32.268178] systemd[1]: Reload requested from client PID 1742 ('.switch-to-conf') (unit backdoor.service)... machine # [ 32.270191] systemd[1]: Reloading... machine # [ 32.718277] systemd[1]: Reloading finished in 446 ms. machine # [ 32.808442] systemd[581]: Reexecution requested from client PID 1810 ('switch-to-confi')... machine # [ 32.811856] systemd[581]: Reexecuting. machine # [ 32.859596] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.978273] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.985056] systemd[581]: Reload requested from client PID 1810 ('switch-to-confi')... machine # [ 32.986415] systemd[581]: Reloading... machine # [ 33.101279] systemd[581]: Reloading finished in 112 ms. machine # [ 33.116207] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.123385] systemd[581]: Stopped usertest.service. machine # [ 33.125715] systemd[581]: Stopping usertest.service... machine # [ 33.127850] systemd[581]: Starting usertest.service... machine # [ 33.154693] systemd[581]: Finished usertest.service. machine # [ 33.159683] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 33.162224] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 33.164760] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 33.199520] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 33.204489] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 33.205471] systemd[1]: Stopping Reactivate sysinit units... machine # [ 33.206255] systemd[1]: Reached target Reactivate sysinit units. machine # [ 33.210531] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.211926] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 33.220488] systemd[1]: Reached target Local File Systems. machine # [ 33.221396] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 33.225705] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 33.226656] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 33.227885] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 33.240256] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 33.241958] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.243187] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.245315] systemd[1]: Reached target Remote File Systems. machine # [ 33.414292] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 33.419239] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 33.422409] systemd[1]: Starting linger-users.service... machine # [ 33.462623] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 33.465753] systemd[1]: Finished linger-users.service. machine # [ 33.722945] nixos[1742]: finished switching to system configuration /nix/store/a8iaf9wvnin33cj060m3q3zzx4qmnz9b-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserServiceNostop/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 usertest.service machine # [ 33.801614] sudo[1881]: 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.819010] sudo[1881]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 33.834222] sudo[1881]: 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/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1 machine # [ 34.056146] nixos[1890]: switching to system configuration /nix/store/50qcsx3w4pq02qdi47da0skjd88vp1p8-nixos-system-machine-test machine # [ 34.059689] systemd[1]: Stopped target Remote File Systems. machine # [ 34.060893] systemd[1]: Stopped target Local File Systems. machine # [ 34.367114] systemd[1]: Reload requested from client PID 1890 ('.switch-to-conf') (unit backdoor.service)... machine # [ 34.369747] systemd[1]: Reloading... machine # [ 34.869618] systemd[1]: Reloading finished in 497 ms. machine # [ 34.998259] systemd[581]: Reexecution requested from client PID 1959 ('switch-to-confi')... machine # [ 35.002616] systemd[581]: Reexecuting. machine # [ 35.055568] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.179128] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.187327] systemd[581]: Reload requested from client PID 1959 ('switch-to-confi')... machine # [ 35.190074] systemd[581]: Reloading... machine # [ 35.312175] systemd[581]: Reloading finished in 121 ms. machine # [ 35.328251] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 35.338041] systemd[581]: Reloading usertest.service... machine # [ 35.367724] systemd[581]: Reloaded usertest.service. machine # [ 35.373256] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 35.375706] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 35.378352] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 35.414554] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 35.419287] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 35.422355] systemd[1]: Stopping Reactivate sysinit units... machine # [ 35.423256] systemd[1]: Reached target Reactivate sysinit units. machine # [ 35.427311] systemd[1]: Reached target Local File Systems. machine # [ 35.430503] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 35.439232] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 35.444745] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 35.446089] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 35.453897] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 35.455666] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 35.458725] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 35.460084] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 35.461473] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 35.462799] systemd[1]: Reached target Remote File Systems. machine # [ 35.631199] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 35.636262] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 35.638498] systemd[1]: Starting linger-users.service... machine # [ 35.684189] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 35.687393] systemd[1]: Finished linger-users.service. machine # [ 35.942441] nixos[1890]: finished switching to system configuration /nix/store/50qcsx3w4pq02qdi47da0skjd88vp1p8-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserServiceReload/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 usertest.service machine # [ 36.013975] sudo[2030]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 36.032906] sudo[2030]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 36.047004] sudo[2030]: 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/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 36.255847] nixos[2039]: switching to system configuration /nix/store/ydz9rnv2xwg2gpnvrbs6c3d2wn1s9s4w-nixos-system-machine-test machine # [ 36.258604] systemd[1]: Stopped target Local File Systems. machine # [ 36.261502] systemd[1]: Stopped target Remote File Systems. machine # [ 36.526969] systemd[1]: Reload requested from client PID 2039 ('.switch-to-conf') (unit backdoor.service)... machine # [ 36.529094] systemd[1]: Reloading... machine # [ 36.864976] systemd[1]: Reloading finished in 334 ms. machine # [ 36.955366] systemd[581]: Stopped usertest.service. machine # [ 36.957180] systemd[581]: Reexecution requested from client PID 2108 ('switch-to-confi')... machine # [ 36.960300] systemd[581]: Reexecuting. machine # [ 37.020834] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.142246] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.149224] systemd[581]: Reload requested from client PID 2108 ('switch-to-confi')... machine # [ 37.151879] systemd[581]: Reloading... machine # [ 37.269473] systemd[581]: Reloading finished in 116 ms. machine # [ 37.284211] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 37.295887] systemd[581]: Starting usertest.service... machine # [ 37.327175] systemd[581]: Finished usertest.service. machine # [ 37.330367] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 37.332881] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 37.335222] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 37.371138] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 37.375821] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 37.378516] systemd[1]: Stopping Reactivate sysinit units... machine # [ 37.379424] systemd[1]: Reached target Reactivate sysinit units. machine # [ 37.385098] systemd[1]: Reached target Local File Systems. machine # [ 37.386010] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 37.396431] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 37.397482] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 37.398787] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 37.407331] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 37.416609] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.418081] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.420602] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 37.422506] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 37.424328] systemd[1]: Reached target Remote File Systems. machine # [ 37.587178] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 37.591766] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 37.594668] systemd[1]: Starting linger-users.service... machine # [ 37.639721] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 37.642684] systemd[1]: Finished linger-users.service. machine # [ 37.901069] nixos[2039]: finished switching to system configuration /nix/store/ydz9rnv2xwg2gpnvrbs6c3d2wn1s9s4w-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.86 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 37.975703] sudo[2178]: 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.994239] sudo[2178]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 38.009528] sudo[2178]: 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/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1 machine # [ 38.279134] nixos[2187]: switching to system configuration /nix/store/vx73wjicaqq07j8d3f1lwd8m2dv2rq5c-nixos-system-machine-test machine # [ 38.284895] systemd[1]: Stopped target Remote File Systems. machine # [ 38.286690] systemd[1]: Stopped target Local File Systems. machine # [ 38.584455] systemd[1]: Reload requested from client PID 2187 ('.switch-to-conf') (unit backdoor.service)... machine # [ 38.586112] systemd[1]: Reloading... machine # [ 38.963948] systemd[1]: Reloading finished in 376 ms. machine # [ 39.063012] systemd[581]: Reexecution requested from client PID 2257 ('switch-to-confi')... machine # [ 39.066564] systemd[581]: Reexecuting. machine # [ 39.119209] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.281726] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.289910] systemd[581]: Reload requested from client PID 2257 ('switch-to-confi')... machine # [ 39.292850] systemd[581]: Reloading... machine # [ 39.409300] systemd[581]: Reloading finished in 114 ms. machine # [ 39.424194] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 39.433046] systemd[581]: Reloading usertest.service... machine # [ 39.458265] systemd[581]: Reloaded usertest.service. machine # [ 39.463012] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 39.465402] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 39.467430] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 39.499943] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 39.503966] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 39.505454] systemd[1]: Stopping Reactivate sysinit units... machine # [ 39.506282] systemd[1]: Reached target Reactivate sysinit units. machine # [ 39.511697] systemd[1]: Reached target Local File Systems. machine # [ 39.516208] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 39.517786] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 39.530064] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 39.531469] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 39.536913] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 39.538554] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.539792] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.541110] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 39.542389] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 39.543636] systemd[1]: Reached target Remote File Systems. machine # [ 39.705996] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 39.711330] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 39.714200] systemd[1]: Starting linger-users.service... machine # [ 39.762708] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 39.764633] systemd[1]: Finished linger-users.service. machine # [ 40.023758] nixos[2187]: finished switching to system configuration /nix/store/vx73wjicaqq07j8d3f1lwd8m2dv2rq5c-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 2.02 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 40.099597] sudo[2327]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 40.121515] sudo[2327]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 40.140359] sudo[2327]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.11 seconds) machine: must fail: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1 machine # [ 40.365017] nixos[2336]: switching to system configuration /nix/store/qc65grcdj9gag3m6psyy82x3f1ydly45-nixos-system-machine-test machine # [ 40.367827] systemd[1]: Stopped target Local File Systems. machine # [ 40.370317] systemd[1]: Stopped target Remote File Systems. machine # [ 40.673648] systemd[1]: Reload requested from client PID 2336 ('.switch-to-conf') (unit backdoor.service)... machine # [ 40.675670] systemd[1]: Reloading... machine # [ 41.014184] systemd[1]: Reloading finished in 336 ms. machine # [ 41.110563] systemd[581]: Stopped usertest.service. machine # [ 41.113843] systemd[581]: Reexecution requested from client PID 2405 ('switch-to-confi')... machine # [ 41.115184] systemd[581]: Reexecuting. machine # [ 41.172287] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 41.295766] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 41.302567] systemd[581]: Reload requested from client PID 2405 ('switch-to-confi')... machine # [ 41.306870] systemd[581]: Reloading... machine # [ 41.420606] systemd[581]: Reloading finished in 114 ms. machine # [ 41.436200] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 41.451273] systemd[581]: Starting usertest.service... machine # [ 41.497574] systemd[581]: usertest.service: Main process exited, code=exited, status=1/FAILURE machine # [ 41.499062] systemd[581]: usertest.service: Failed with result 'exit-code'. machine # [ 41.504422] systemd[581]: Failed to start usertest.service. machine # [ 41.507926] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 41.510081] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 41.519784] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 41.559523] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 41.564849] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 41.565825] systemd[1]: Stopping Reactivate sysinit units... machine # [ 41.566644] systemd[1]: Reached target Reactivate sysinit units. machine # [ 41.570457] systemd[1]: Reached target Remote File Systems. machine # [ 41.573180] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 41.579308] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 41.580848] systemd[1]: Reached target Local File Systems. machine # [ 41.581651] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 41.585470] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 41.586403] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 41.587630] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 41.596946] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 41.598540] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 41.599785] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 41.771003] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 41.773245] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 41.778194] systemd[1]: Starting linger-users.service... machine # [ 41.818824] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 41.821563] systemd[1]: Finished linger-users.service. machine # [ 42.079984] nixos[2336]: switching to system configuration /nix/store/qc65grcdj9gag3m6psyy82x3f1ydly45-nixos-system-machine-test failed (status 4) machine: (finished: must fail: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 1.95 seconds) machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 42.353917] nixos[2479]: switching to system configuration /nix/store/ydz9rnv2xwg2gpnvrbs6c3d2wn1s9s4w-nixos-system-machine-test machine # [ 42.356365] systemd[1]: Stopped target Local File Systems. machine # [ 42.358663] systemd[1]: Stopped target Remote File Systems. machine # [ 42.628598] systemd[1]: Reload requested from client PID 2479 ('.switch-to-conf') (unit backdoor.service)... machine # [ 42.630173] systemd[1]: Reloading... machine # [ 43.107624] systemd[1]: Reloading finished in 476 ms. machine # [ 43.196422] systemd[581]: Reexecution requested from client PID 2548 ('switch-to-confi')... machine # [ 43.200489] systemd[581]: Reexecuting. machine # [ 43.255508] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.374295] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.381743] systemd[581]: Reload requested from client PID 2548 ('switch-to-confi')... machine # [ 43.383065] systemd[581]: Reloading... machine # [ 43.495165] systemd[581]: Reloading finished in 110 ms. machine # [ 43.512181] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 43.523221] systemd[581]: Starting usertest.service... machine # [ 43.550103] systemd[581]: Finished usertest.service. machine # [ 43.552451] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 43.554691] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 43.556819] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 43.588748] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 43.592257] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 43.594996] systemd[1]: Stopping Reactivate sysinit units... machine # [ 43.595906] systemd[1]: Reached target Reactivate sysinit units. machine # [ 43.600466] systemd[1]: Reached target Remote File Systems. machine # [ 43.602786] systemd[1]: Starting linger-users.service... machine # [ 43.614285] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.615747] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 43.629278] systemd[1]: Reached target Local File Systems. machine # [ 43.630155] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 43.638772] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 43.639730] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 43.641812] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 43.646494] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 43.651397] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.656916] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.694123] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 43.697374] systemd[1]: Finished linger-users.service. machine # [ 43.856548] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 43.859574] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 44.118380] nixos[2479]: finished switching to system configuration /nix/store/ydz9rnv2xwg2gpnvrbs6c3d2wn1s9s4w-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 44.193334] sudo[2618]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 44.214522] sudo[2618]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 44.231016] sudo[2618]: 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/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 44.446955] nixos[2627]: switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test machine # [ 44.450991] systemd[1]: Stopped target Local File Systems. machine # [ 44.451877] systemd[1]: Stopped target Remote File Systems. machine # [ 44.719219] systemd[1]: Reload requested from client PID 2627 ('.switch-to-conf') (unit backdoor.service)... machine # [ 44.721455] systemd[1]: Reloading... machine # [ 45.059035] systemd[1]: Reloading finished in 336 ms. machine # [ 45.182907] systemd[581]: Stopped usertest.service. machine # [ 45.186820] systemd[581]: Reexecution requested from client PID 2696 ('switch-to-confi')... machine # [ 45.188933] systemd[581]: Reexecuting. machine # [ 45.222923] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.335108] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.341788] systemd[581]: Reload requested from client PID 2696 ('switch-to-confi')... machine # [ 45.344293] systemd[581]: Reloading... machine # [ 45.454139] systemd[581]: Reloading finished in 108 ms. machine # [ 45.468180] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 45.477774] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 45.480328] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 45.482495] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 45.516669] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 45.520153] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 45.522674] systemd[1]: Stopping Reactivate sysinit units... machine # [ 45.523579] systemd[1]: Reached target Reactivate sysinit units. machine # [ 45.526424] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 45.527755] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 45.532292] systemd[1]: Reached target Remote File Systems. machine # [ 45.535629] systemd[1]: Starting linger-users.service... machine # [ 45.538808] systemd[1]: Reached target Local File Systems. machine # [ 45.550735] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 45.556346] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 45.557270] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 45.558528] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 45.570799] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 45.575648] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 45.582182] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 45.623057] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 45.626142] systemd[1]: Finished linger-users.service. machine # [ 45.765765] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 45.768549] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 46.027173] nixos[2627]: finished switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.80 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 46.107696] sudo[2765]: 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 # [ 46.129278] sudo[2765]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 46.147689] sudo[2765]: 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.11 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 46.185900] sudo[2771]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 46.198165] sudo[2771]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 46.207790] sudo[2771]: 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/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 46.238812] sudo[2777]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 46.249851] sudo[2777]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 46.259191] sudo[2777]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 46.287590] sudo[2783]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 46.298103] sudo[2783]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 46.307270] sudo[2783]: 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 # [ 46.335785] sudo[2789]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload machine # [ 46.345837] sudo[2789]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 46.354264] systemd[581]: Reload requested from client PID 2794 ('systemctl')... machine # [ 46.358217] systemd[581]: Reloading... machine # [ 46.471673] systemd[581]: Reloading finished in 114 ms. machine # [ 46.488181] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.499542] sudo[2789]: 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 # [ 46.531132] sudo[2799]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service machine # [ 46.541731] sudo[2799]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 46.557276] systemd[581]: Starting migrated.service... machine # [ 46.585143] systemd[581]: Finished migrated.service. machine # [ 46.590676] sudo[2799]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 46.622864] sudo[2807]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 46.633474] sudo[2807]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 46.645237] sudo[2807]: 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 # [ 46.676595] sudo[2813]: 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 # [ 46.686808] sudo[2813]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 46.695827] sudo[2813]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 46.727334] sudo[2819]: 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 # [ 46.738018] sudo[2819]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 46.756264] sudo[2819]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds) machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1 machine # [ 46.959716] nixos[2828]: switching to system configuration /nix/store/myjnxfyq1mwc9jy082z37f6ldmvx2v36-nixos-system-machine-test machine # [ 46.962691] systemd[1]: Stopped target Local File Systems. machine # [ 46.965109] systemd[1]: Stopped target Remote File Systems. machine # [ 47.254917] systemd[1]: Reload requested from client PID 2828 ('.switch-to-conf') (unit backdoor.service)... machine # [ 47.256973] systemd[1]: Reloading... machine # [ 47.577554] systemd[1]: Reloading finished in 318 ms. machine # [ 47.671368] systemd[581]: Reexecution requested from client PID 2897 ('switch-to-confi')... machine # [ 47.674268] systemd[581]: Reexecuting. machine # [ 47.732511] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 47.853320] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 47.860114] systemd[581]: Reload requested from client PID 2897 ('switch-to-confi')... machine # [ 47.861529] systemd[581]: Reloading... machine # [ 47.974669] systemd[581]: Reloading finished in 111 ms. machine # [ 47.992197] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.001372] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 48.003824] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 48.006110] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 48.070394] systemd[581]: Reload requested from client PID 2909 ('systemctl') (unit nixos-activation.service)... machine # [ 48.075230] systemd[581]: Reloading... machine # [ 48.193960] systemd[581]: Reloading finished in 120 ms. machine # [ 48.212223] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.232773] systemd[581]: Stopped migrated.service. machine # [ 48.244166] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 48.246981] systemd[581]: Reload requested from client PID 2897 ('switch-to-confi')... machine # [ 48.251288] systemd[581]: Reloading... machine # [ 48.366094] systemd[581]: Reloading finished in 114 ms. machine # [ 48.380177] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 48.391992] systemd[581]: Starting migrated.service... machine # [ 48.419704] systemd[581]: Finished migrated.service. machine # [ 48.424487] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 48.428361] systemd[1]: Stopping Reactivate sysinit units... machine # [ 48.429338] systemd[1]: Reached target Reactivate sysinit units. machine # [ 48.430329] systemd[1]: Reached target Local File Systems. machine # [ 48.433852] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 48.444253] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 48.445236] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 48.446451] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 48.455668] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 48.459120] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.460894] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.462215] systemd[1]: Reached target Remote File Systems. machine # [ 48.463370] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 48.465418] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 48.630006] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 48.634953] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 48.637716] systemd[1]: Starting linger-users.service... machine # [ 48.682404] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 48.685434] systemd[1]: Finished linger-users.service. machine # [ 48.946302] nixos[2828]: finished switching to system configuration /nix/store/myjnxfyq1mwc9jy082z37f6ldmvx2v36-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 2.20 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 49.021890] sudo[2979]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 49.043731] sudo[2979]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 49.061286] sudo[2979]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.11 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 49.100553] sudo[2985]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 49.114721] sudo[2985]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 49.135090] sudo[2985]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 49.165523] sudo[2991]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 49.175590] sudo[2991]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 49.184288] sudo[2991]: 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/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 49.385253] nixos[3000]: switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test machine # [ 49.388800] systemd[1]: Stopped target Remote File Systems. machine # [ 49.389742] systemd[1]: Stopped target Local File Systems. machine # [ 49.663625] systemd[1]: Reload requested from client PID 3000 ('.switch-to-conf') (unit backdoor.service)... machine # [ 49.665687] systemd[1]: Reloading... machine # [ 49.987121] systemd[1]: Reloading finished in 320 ms. machine # [ 50.080961] systemd[581]: Stopped migrated.service. machine # [ 50.084806] systemd[581]: Reexecution requested from client PID 3069 ('switch-to-confi')... machine # [ 50.086177] systemd[581]: Reexecuting. machine # [ 50.143721] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 50.261547] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 50.268345] systemd[581]: Reload requested from client PID 3069 ('switch-to-confi')... machine # [ 50.272212] systemd[581]: Reloading... machine # [ 50.381911] systemd[581]: Reloading finished in 110 ms. machine # [ 50.396190] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 50.405463] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 50.407822] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 50.410029] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 50.439771] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 50.443756] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 50.449166] systemd[1]: Stopping Reactivate sysinit units... machine # [ 50.450066] systemd[1]: Reached target Reactivate sysinit units. machine # [ 50.450888] systemd[1]: Reached target Local File Systems. machine # [ 50.451670] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 50.456408] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 50.457363] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 50.458554] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 50.471673] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 50.477810] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 50.479145] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 50.481069] systemd[1]: Reached target Remote File Systems. machine # [ 50.481930] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 50.483586] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 50.641211] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 50.642911] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 50.647813] systemd[1]: Starting linger-users.service... machine # [ 50.693419] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 50.696816] systemd[1]: Finished linger-users.service. machine # [ 50.954433] nixos[3000]: finished switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.78 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 51.032612] sudo[3137]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 51.053178] sudo[3137]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 51.071187] sudo[3137]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.11 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 51.107990] sudo[3143]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 51.120827] sudo[3143]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 51.131044] sudo[3143]: 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/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 51.165076] sudo[3149]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 51.175934] sudo[3149]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 51.184998] sudo[3149]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 51.214695] sudo[3155]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 51.225002] sudo[3155]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 51.233489] sudo[3155]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 51.263480] sudo[3161]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload machine # [ 51.274027] sudo[3161]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 51.283217] systemd[581]: Reload requested from client PID 3166 ('systemctl')... machine # [ 51.287707] systemd[581]: Reloading... machine # [ 51.401750] systemd[581]: Reloading finished in 115 ms. machine # [ 51.416249] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 51.428115] sudo[3161]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.19 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 51.457429] sudo[3171]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service machine # [ 51.467251] sudo[3171]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 51.480968] systemd[581]: Starting migrated.service... machine # [ 51.507946] systemd[581]: Finished migrated.service. machine # [ 51.512196] sudo[3171]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 51.541465] sudo[3179]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 51.551212] sudo[3179]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 51.562073] sudo[3179]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 51.591116] sudo[3185]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 51.601371] sudo[3185]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 51.609652] sudo[3185]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 51.637983] sudo[3191]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 51.647857] sudo[3191]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 51.664612] sudo[3191]: 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/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1 machine # [ 51.862728] nixos[3200]: switching to system configuration /nix/store/03z9zcv1kgd2f5s3mi96w4zwxjz7skhk-nixos-system-machine-test machine # [ 51.865624] systemd[1]: Stopped target Local File Systems. machine # [ 51.867857] systemd[1]: Stopped target Remote File Systems. machine # [ 52.154289] systemd[1]: Reload requested from client PID 3200 ('.switch-to-conf') (unit backdoor.service)... machine # [ 52.156309] systemd[1]: Reloading... machine # [ 52.485222] systemd[1]: Reloading finished in 327 ms. machine # [ 52.584264] systemd[581]: Reexecution requested from client PID 3269 ('switch-to-confi')... machine # [ 52.588634] systemd[581]: Reexecuting. machine # [ 52.643784] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.769900] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.777050] systemd[581]: Reload requested from client PID 3269 ('switch-to-confi')... machine # [ 52.779489] systemd[581]: Reloading... machine # [ 52.903139] systemd[581]: Reloading finished in 122 ms. machine # [ 52.920211] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 52.930909] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 52.931991] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 52.934510] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 52.974869] systemd[581]: Reload requested from client PID 3281 ('systemctl') (unit nixos-activation.service)... machine # [ 52.979731] systemd[581]: Reloading... machine # [ 53.098688] systemd[581]: Reloading finished in 120 ms. machine # [ 53.116199] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.133199] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 53.135890] systemd[581]: Reload requested from client PID 3269 ('switch-to-confi')... machine # [ 53.139959] systemd[581]: Reloading... machine # [ 53.253927] systemd[581]: Reloading finished in 113 ms. machine # [ 53.268173] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.279729] systemd[581]: Stopped migrated.service. machine # [ 53.280781] systemd[581]: Stopping migrated.service... machine # [ 53.282607] systemd[581]: Starting migrated.service... machine # [ 53.310937] systemd[581]: Finished migrated.service. machine # [ 53.317602] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 53.319993] systemd[1]: Stopping Reactivate sysinit units... machine # [ 53.321704] systemd[1]: Reached target Reactivate sysinit units. machine # [ 53.323362] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.328582] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 53.329901] systemd[1]: Reached target Remote File Systems. machine # [ 53.333302] systemd[1]: Starting linger-users.service... machine # [ 53.345694] systemd[1]: Reached target Local File Systems. machine # [ 53.346602] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 53.358563] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 53.359456] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 53.360898] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 53.363098] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 53.367018] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.371139] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.418524] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 53.422228] systemd[1]: Finished linger-users.service. machine # [ 53.556359] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 53.559604] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 53.818347] nixos[3200]: finished switching to system configuration /nix/store/03z9zcv1kgd2f5s3mi96w4zwxjz7skhk-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 2.17 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 53.897297] sudo[3350]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 53.917278] sudo[3350]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 53.932296] sudo[3350]: 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.10 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 53.969495] sudo[3356]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 53.982758] sudo[3356]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 54.005449] sudo[3356]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 54.039008] sudo[3362]: 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.051129] sudo[3362]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 54.059774] sudo[3362]: 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/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 54.272338] nixos[3371]: switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test machine # [ 54.274819] systemd[1]: Stopped target Local File Systems. machine # [ 54.277215] systemd[1]: Stopped target Remote File Systems. machine # [ 54.547521] systemd[1]: Reload requested from client PID 3371 ('.switch-to-conf') (unit backdoor.service)... machine # [ 54.549663] systemd[1]: Reloading... machine # [ 54.875089] systemd[1]: Reloading finished in 324 ms. machine # [ 54.966341] systemd[581]: Stopped migrated.service. machine # [ 54.970785] systemd[581]: Reexecution requested from client PID 3440 ('switch-to-confi')... machine # [ 54.972881] systemd[581]: Reexecuting. machine # [ 55.031970] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.152828] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.159399] systemd[581]: Reload requested from client PID 3440 ('switch-to-confi')... machine # [ 55.162871] systemd[581]: Reloading... machine # [ 55.272191] systemd[581]: Reloading finished in 110 ms. machine # [ 55.288179] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 55.299976] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 55.301091] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 55.303675] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 55.336609] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 55.340641] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 55.343449] systemd[1]: Stopping Reactivate sysinit units... machine # [ 55.344978] systemd[1]: Reached target Reactivate sysinit units. machine # [ 55.345860] systemd[1]: Reached target Remote File Systems. machine # [ 55.350334] systemd[1]: Starting linger-users.service... machine # [ 55.359947] systemd[1]: Reached target Local File Systems. machine # [ 55.366684] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 55.375119] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 55.384432] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 55.386487] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 55.392952] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 55.395373] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.399336] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.405763] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 55.407165] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 55.448757] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 55.452607] systemd[1]: Finished linger-users.service. machine # [ 55.593762] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 55.597268] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 55.854427] nixos[3371]: finished switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.81 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 55.929792] sudo[3508]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 55.945942] sudo[3508]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 55.961750] sudo[3508]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 55.997514] sudo[3514]: 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.009110] sudo[3514]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.018535] sudo[3514]: 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/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 56.049891] sudo[3520]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 56.060614] sudo[3520]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.069083] sudo[3520]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 56.097645] sudo[3526]: 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.109083] sudo[3526]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.117526] sudo[3526]: 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 # [ 56.145746] sudo[3532]: 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.155337] sudo[3532]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.164172] systemd[581]: Reload requested from client PID 3537 ('systemctl')... machine # [ 56.168191] systemd[581]: Reloading... machine # [ 56.281329] systemd[581]: Reloading finished in 114 ms. machine # [ 56.297649] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 56.308554] sudo[3532]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.19 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 56.337273] sudo[3542]: 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.346925] sudo[3542]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.360405] systemd[581]: Starting migrated.service... machine # [ 56.387517] systemd[581]: Finished migrated.service. machine # [ 56.391872] sudo[3542]: 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.420980] sudo[3550]: 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.430210] sudo[3550]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.440754] sudo[3550]: 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.468939] sudo[3556]: 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.478545] sudo[3556]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.486858] sudo[3556]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 56.515140] sudo[3562]: 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.525140] sudo[3562]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 56.541826] sudo[3562]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds) machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1 machine # [ 56.743196] nixos[3571]: switching to system configuration /nix/store/kb8dr6qa9mngxf2d7faxshwsspkc5r1r-nixos-system-machine-test machine # [ 56.746273] systemd[1]: Stopped target Local File Systems. machine # [ 56.747973] systemd[1]: Stopped target Remote File Systems. machine # [ 57.037998] systemd[1]: Reload requested from client PID 3571 ('.switch-to-conf') (unit backdoor.service)... machine # [ 57.039898] systemd[1]: Reloading... machine # [ 57.365950] systemd[1]: Reloading finished in 324 ms. machine # [ 57.458996] systemd[581]: Reexecution requested from client PID 3640 ('switch-to-confi')... machine # [ 57.461921] systemd[581]: Reexecuting. machine # [ 57.519964] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.647182] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.654263] systemd[581]: Reload requested from client PID 3640 ('switch-to-confi')... machine # [ 57.658299] systemd[581]: Reloading... machine # [ 57.774173] systemd[581]: Reloading finished in 116 ms. machine # [ 57.788413] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 57.798089] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 57.800836] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 57.803204] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 57.844196] systemd[581]: Reload requested from client PID 3652 ('systemctl') (unit nixos-activation.service)... machine # [ 57.847179] systemd[581]: Reloading... machine # [ 57.969265] systemd[581]: Reloading finished in 120 ms. machine # [ 57.984182] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 58.003713] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 58.006730] systemd[581]: Reload requested from client PID 3640 ('switch-to-confi')... machine # [ 58.010801] systemd[581]: Reloading... machine # [ 58.126207] systemd[581]: Reloading finished in 116 ms. machine # [ 58.140180] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 58.150637] systemd[581]: Reloading migrated.service... machine # [ 58.177837] systemd[581]: Reloaded migrated.service. machine # [ 58.184579] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 58.189033] systemd[1]: Stopping Reactivate sysinit units... machine # [ 58.189930] systemd[1]: Reached target Reactivate sysinit units. machine # [ 58.190771] systemd[1]: Reached target Local File Systems. machine # [ 58.192911] systemd[1]: Reached target Remote File Systems. machine # [ 58.197494] systemd[1]: Starting linger-users.service... machine # [ 58.207899] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 58.210248] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 58.216085] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 58.222975] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 58.223912] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 58.232563] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 58.234817] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 58.243446] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 58.245379] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 58.280892] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 58.284310] systemd[1]: Finished linger-users.service. machine # [ 58.431741] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 58.434448] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 58.696991] nixos[3571]: finished switching to system configuration /nix/store/kb8dr6qa9mngxf2d7faxshwsspkc5r1r-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 2.17 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 58.774171] sudo[3721]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 58.793184] sudo[3721]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 58.808662] sudo[3721]: 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.10 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 58.844605] sudo[3727]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 58.857293] sudo[3727]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 58.866972] sudo[3727]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds) machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 59.069964] nixos[3736]: switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test machine # [ 59.073555] systemd[1]: Stopped target Remote File Systems. machine # [ 59.074429] systemd[1]: Stopped target Local File Systems. machine # [ 59.338583] systemd[1]: Reload requested from client PID 3736 ('.switch-to-conf') (unit backdoor.service)... machine # [ 59.340541] systemd[1]: Reloading... machine # [ 59.654656] systemd[1]: Reloading finished in 313 ms. machine # [ 59.744505] systemd[581]: Stopped migrated.service. machine # [ 59.748205] systemd[581]: Reexecution requested from client PID 3804 ('switch-to-confi')... machine # [ 59.749596] systemd[581]: Reexecuting. machine # [ 59.803322] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.920196] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.926791] systemd[581]: Reload requested from client PID 3804 ('switch-to-confi')... machine # [ 59.930773] systemd[581]: Reloading... machine # [ 60.039455] systemd[581]: Reloading finished in 110 ms. machine # [ 60.056212] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 60.066913] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 60.069309] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 60.071480] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 60.104292] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 60.108178] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 60.110079] systemd[1]: Stopping Reactivate sysinit units... machine # [ 60.110961] systemd[1]: Reached target Reactivate sysinit units. machine # [ 60.113502] systemd[1]: Reached target Remote File Systems. machine # [ 60.117755] systemd[1]: Starting linger-users.service... machine # [ 60.126579] systemd[1]: Reached target Local File Systems. machine # [ 60.131414] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 60.139517] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 60.148444] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 60.150518] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 60.152438] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 60.157604] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 60.159794] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 60.167631] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 60.171623] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 60.204739] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 60.209034] systemd[1]: Finished linger-users.service. machine # [ 60.347039] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 60.350494] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 60.607555] nixos[3736]: finished switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.75 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 60.680391] sudo[3873]: 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.697964] sudo[3873]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 60.714939] sudo[3873]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 60.757717] sudo[3879]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 60.771176] sudo[3879]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 60.781020] sudo[3879]: 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/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 60.811430] sudo[3885]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 60.822580] sudo[3885]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 60.831094] sudo[3885]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 60.858419] sudo[3891]: 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 # [ 60.868845] sudo[3891]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 60.876099] sudo[3891]: 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 # [ 60.903146] sudo[3897]: 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.913283] sudo[3897]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 60.921537] systemd[581]: Reload requested from client PID 3902 ('systemctl')... machine # [ 60.925501] systemd[581]: Reloading... machine # [ 61.039593] systemd[581]: Reloading finished in 115 ms. machine # [ 61.056177] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 61.067647] sudo[3897]: 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 # [ 61.095468] sudo[3907]: 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.105195] sudo[3907]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 61.118258] systemd[581]: Starting migrated.service... machine # [ 61.147285] systemd[581]: Finished migrated.service. machine # [ 61.151425] sudo[3907]: 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.178972] sudo[3915]: 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.188714] sudo[3915]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 61.198752] sudo[3915]: 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.225997] sudo[3921]: 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.235448] sudo[3921]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 61.243439] sudo[3921]: 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.270435] sudo[3927]: 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.280785] sudo[3927]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 61.296264] sudo[3927]: 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/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1 machine # [ 61.495267] nixos[3936]: switching to system configuration /nix/store/f20r1s1n98n4srlr7pdpxx9aklinbxp1-nixos-system-machine-test machine # [ 61.499268] systemd[1]: Stopped target Remote File Systems. machine # [ 61.500367] systemd[1]: Stopped target Local File Systems. machine # [ 61.784864] systemd[1]: Reload requested from client PID 3936 ('.switch-to-conf') (unit backdoor.service)... machine # [ 61.786810] systemd[1]: Reloading... machine # [ 62.125546] systemd[1]: Reloading finished in 336 ms. machine # [ 62.218877] systemd[581]: Reexecution requested from client PID 4005 ('switch-to-confi')... machine # [ 62.222794] systemd[581]: Reexecuting. machine # [ 62.275450] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.401333] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.408116] systemd[581]: Reload requested from client PID 4005 ('switch-to-confi')... machine # [ 62.412170] systemd[581]: Reloading... machine # [ 62.527787] systemd[581]: Reloading finished in 116 ms. machine # [ 62.544211] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.554740] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 62.555815] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 62.558501] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 62.597699] systemd[581]: Reload requested from client PID 4017 ('systemctl') (unit nixos-activation.service)... machine # [ 62.602174] systemd[581]: Reloading... machine # [ 62.722164] systemd[581]: Reloading finished in 121 ms. machine # [ 62.736184] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.753795] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 62.757202] systemd[581]: Reload requested from client PID 4005 ('switch-to-confi')... machine # [ 62.758496] systemd[581]: Reloading... machine # [ 62.873420] systemd[581]: Reloading finished in 113 ms. machine # [ 62.888183] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.901588] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 62.902608] systemd[1]: Stopping Reactivate sysinit units... machine # [ 62.903394] systemd[1]: Reached target Reactivate sysinit units. machine # [ 62.907588] systemd[1]: Reached target Local File Systems. machine # [ 62.912230] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.913590] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 62.914848] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 62.929108] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 62.930014] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 62.931257] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 62.937666] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 62.939971] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.943313] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 62.945293] systemd[1]: Reached target Remote File Systems. machine # [ 63.101116] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 63.104573] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 63.108233] systemd[1]: Starting linger-users.service... machine # [ 63.150478] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 63.153623] systemd[1]: Finished linger-users.service. machine # [ 63.414679] nixos[3936]: finished switching to system configuration /nix/store/f20r1s1n98n4srlr7pdpxx9aklinbxp1-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 2.13 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 63.497215] sudo[4084]: 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.519985] sudo[4084]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 63.537046] sudo[4084]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.11 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 63.573537] sudo[4090]: 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.586420] sudo[4090]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 63.596219] sudo[4090]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds) machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 63.800982] nixos[4099]: switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test machine # [ 63.803840] systemd[1]: Stopped target Local File Systems. machine # [ 63.806524] systemd[1]: Stopped target Remote File Systems. machine # [ 64.072176] systemd[1]: Reload requested from client PID 4099 ('.switch-to-conf') (unit backdoor.service)... machine # [ 64.074089] systemd[1]: Reloading... machine # [ 64.391612] systemd[1]: Reloading finished in 316 ms. machine # [ 64.481576] systemd[581]: Stopped migrated.service. machine # [ 64.484502] systemd[581]: Reexecution requested from client PID 4168 ('switch-to-confi')... machine # [ 64.487382] systemd[581]: Reexecuting. machine # [ 64.547515] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 64.665756] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 64.672093] systemd[581]: Reload requested from client PID 4168 ('switch-to-confi')... machine # [ 64.675598] systemd[581]: Reloading... machine # [ 64.785896] systemd[581]: Reloading finished in 111 ms. machine # [ 64.800176] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 64.808761] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 64.811045] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 64.813143] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 64.843976] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 64.847503] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 64.849185] systemd[1]: Stopping Reactivate sysinit units... machine # [ 64.849995] systemd[1]: Reached target Reactivate sysinit units. machine # [ 64.854483] systemd[1]: Reached target Remote File Systems. machine # [ 64.858803] systemd[1]: Starting linger-users.service... machine # [ 64.868469] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 64.869832] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 64.874297] systemd[1]: Reached target Local File Systems. machine # [ 64.875966] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 64.886045] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 64.886951] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 64.894094] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 64.897368] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 64.903529] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 64.906418] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 64.943727] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 64.947399] systemd[1]: Finished linger-users.service. machine # [ 65.086127] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 65.089581] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 65.346438] nixos[4099]: finished switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.76 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 65.423929] sudo[4236]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 65.445407] sudo[4236]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 65.461643] sudo[4236]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user machine # [ 65.496877] sudo[4242]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user machine # [ 65.508922] sudo[4242]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 65.518919] sudo[4242]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user, in 0.06 seconds) machine: must succeed: sudo -u usertest cp /nix/store/kbn5jd7gqb07qksa4hgqhsx409fjnskk-migrated.service ~usertest/.local/share/systemd/user/migrated.service machine # [ 65.549429] sudo[4248]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/kbn5jd7gqb07qksa4hgqhsx409fjnskk-migrated.service /home/usertest/.local/share/systemd/user/migrated.service machine # [ 65.560766] sudo[4248]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 65.568618] sudo[4248]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/kbn5jd7gqb07qksa4hgqhsx409fjnskk-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 65.596251] sudo[4254]: 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.605554] sudo[4254]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 65.613838] systemd[581]: Reload requested from client PID 4259 ('systemctl')... machine # [ 65.617757] systemd[581]: Reloading... machine # [ 65.728944] systemd[581]: Reloading finished in 112 ms. machine # [ 65.744176] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.757129] sudo[4254]: 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 # [ 65.784975] sudo[4264]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service machine # [ 65.794420] sudo[4264]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 65.808788] systemd[581]: Starting migrated.service... machine # [ 65.834959] systemd[581]: Finished migrated.service. machine # [ 65.838708] sudo[4264]: 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 # [ 65.866438] sudo[4272]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 65.876761] sudo[4272]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 65.886022] sudo[4272]: 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 # [ 65.912646] sudo[4278]: 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 # [ 65.921839] sudo[4278]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 65.929308] sudo[4278]: 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 # [ 65.955416] sudo[4284]: 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 # [ 65.964986] sudo[4284]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 65.984941] sudo[4284]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds) machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 66.184972] nixos[4293]: switching to system configuration /nix/store/dyva96i4sypfa7gqsq2nw0np5sb9w6p4-nixos-system-machine-test machine # [ 66.187620] systemd[1]: Stopped target Remote File Systems. machine # [ 66.189837] systemd[1]: Stopped target Local File Systems. machine # [ 66.462659] systemd[1]: Reload requested from client PID 4293 ('.switch-to-conf') (unit backdoor.service)... machine # [ 66.464810] systemd[1]: Reloading... machine # [ 66.770682] systemd[1]: Reloading finished in 304 ms. machine # [ 66.859281] systemd[581]: Reexecution requested from client PID 4362 ('switch-to-confi')... machine # [ 66.863453] systemd[581]: Reexecuting. machine # [ 66.923416] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 67.046509] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 67.052665] systemd[581]: Reload requested from client PID 4362 ('switch-to-confi')... machine # [ 67.056067] systemd[581]: Reloading... machine # [ 67.167982] systemd[581]: Reloading finished in 112 ms. machine # [ 67.184159] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 67.194466] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 67.196789] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 67.198869] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 67.231800] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 67.233933] systemd[581]: Reload requested from client PID 4362 ('switch-to-confi')... machine # [ 67.235163] systemd[581]: Reloading... machine # [ 67.348988] systemd[581]: Reloading finished in 112 ms. machine # [ 67.364163] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 67.375313] systemd[581]: Stopped migrated.service. machine # [ 67.376204] systemd[581]: Stopping migrated.service... machine # [ 67.377946] systemd[581]: Starting migrated.service... machine # [ 67.402433] systemd[581]: Finished migrated.service. machine # [ 67.408349] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 67.411150] systemd[1]: Stopping Reactivate sysinit units... machine # [ 67.413206] systemd[1]: Reached target Reactivate sysinit units. machine # [ 67.416137] systemd[1]: Reached target Remote File Systems. machine # [ 67.419441] systemd[1]: Starting linger-users.service... machine # [ 67.428685] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 67.430331] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 67.435299] systemd[1]: Reached target Local File Systems. machine # [ 67.439500] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 67.444098] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 67.444962] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 67.446144] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 67.457978] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 67.459594] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 67.463694] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 67.501592] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 67.504846] systemd[1]: Finished linger-users.service. machine # [ 67.638631] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 67.641430] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 67.897993] nixos[4293]: finished switching to system configuration /nix/store/dyva96i4sypfa7gqsq2nw0np5sb9w6p4-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.92 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 67.970360] sudo[4436]: 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.989726] sudo[4436]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 68.004815] sudo[4436]: 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.10 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 68.039349] sudo[4442]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 68.053246] sudo[4442]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 68.073163] sudo[4442]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 68.103354] sudo[4448]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 68.113230] sudo[4448]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 68.120782] sudo[4448]: 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/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 68.312656] nixos[4457]: switching to system configuration /nix/store/dyva96i4sypfa7gqsq2nw0np5sb9w6p4-nixos-system-machine-test machine # [ 68.316071] systemd[1]: Stopped target Local File Systems. machine # [ 68.316929] systemd[1]: Stopped target Remote File Systems. machine # [ 68.566225] systemd[1]: Reload requested from client PID 4457 ('.switch-to-conf') (unit backdoor.service)... machine # [ 68.568115] systemd[1]: Reloading... machine # [ 68.867645] systemd[1]: Reloading finished in 298 ms. machine # [ 68.953150] systemd[581]: Reexecution requested from client PID 4526 ('switch-to-confi')... machine # [ 68.956685] systemd[581]: Reexecuting. machine # [ 69.015378] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.133052] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.140101] systemd[581]: Reload requested from client PID 4526 ('switch-to-confi')... machine # [ 69.141419] systemd[581]: Reloading... machine # [ 69.251090] systemd[581]: Reloading finished in 108 ms. machine # [ 69.268167] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.277660] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 69.278659] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 69.281182] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 69.311430] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 69.315147] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 69.317873] systemd[1]: Stopping Reactivate sysinit units... machine # [ 69.318706] systemd[1]: Reached target Reactivate sysinit units. machine # [ 69.323312] systemd[1]: Reached target Local File Systems. machine # [ 69.327531] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 69.332988] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 69.333820] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 69.335026] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 69.341004] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 69.342656] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 69.343931] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 69.353801] systemd[1]: Reached target Remote File Systems. machine # [ 69.354712] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 69.356831] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 69.522417] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 69.524439] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 69.529904] systemd[1]: Starting linger-users.service... machine # [ 69.569377] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 69.572089] systemd[1]: Finished linger-users.service. machine # [ 69.830253] nixos[4457]: finished switching to system configuration /nix/store/dyva96i4sypfa7gqsq2nw0np5sb9w6p4-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.72 seconds) machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd machine # [ 69.903842] sudo[4594]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd machine # [ 69.921500] sudo[4594]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 69.933508] sudo[4594]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.09 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 69.972576] sudo[4600]: 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.986579] sudo[4600]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 69.996632] systemd[581]: Reload requested from client PID 4605 ('systemctl')... machine # [ 70.000318] systemd[581]: Reloading... machine # [ 70.115753] systemd[581]: Reloading finished in 115 ms. machine # [ 70.132192] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 70.142081] sudo[4600]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.21 seconds) machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 70.335851] nixos[4613]: switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test machine # [ 70.338399] systemd[1]: Stopped target Remote File Systems. machine # [ 70.340753] systemd[1]: Stopped target Local File Systems. machine # [ 70.589933] systemd[1]: Reload requested from client PID 4613 ('.switch-to-conf') (unit backdoor.service)... machine # [ 70.591767] systemd[1]: Reloading... machine # [ 70.893223] systemd[1]: Reloading finished in 300 ms. machine # [ 70.982551] systemd[581]: Stopped migrated.service. machine # [ 70.986367] systemd[581]: Reexecution requested from client PID 4682 ('switch-to-confi')... machine # [ 70.987819] systemd[581]: Reexecuting. machine # [ 71.048221] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 71.165161] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 71.171674] systemd[581]: Reload requested from client PID 4682 ('switch-to-confi')... machine # [ 71.173810] systemd[581]: Reloading... machine # [ 71.279917] systemd[581]: Reloading finished in 105 ms. machine # [ 71.297464] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 71.305698] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 71.306658] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 71.308842] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 71.338669] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 71.342355] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 71.345286] systemd[1]: Stopping Reactivate sysinit units... machine # [ 71.346179] systemd[1]: Reached target Reactivate sysinit units. machine # [ 71.347788] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 71.352640] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 71.353955] systemd[1]: Reached target Remote File Systems. machine # [ 71.357495] systemd[1]: Starting linger-users.service... machine # [ 71.361574] systemd[1]: Reached target Local File Systems. machine # [ 71.370379] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 71.371920] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 71.380507] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 71.381802] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 71.383937] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 71.387654] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 71.389089] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 71.445224] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 71.448118] systemd[1]: Finished linger-users.service. machine # [ 71.572177] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 71.574400] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 71.832777] nixos[4613]: finished switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.70 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 71.903201] sudo[4750]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 71.920696] sudo[4750]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 71.931651] sudo[4750]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.09 seconds) machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 71.966253] sudo[4756]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 71.979466] sudo[4756]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 71.988996] sudo[4756]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.06 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 72.018270] sudo[4762]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 72.029194] sudo[4762]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 72.036915] sudo[4762]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 72.062384] sudo[4768]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload machine # [ 72.071500] sudo[4768]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 72.079638] systemd[581]: Reload requested from client PID 4773 ('systemctl')... machine # [ 72.083504] systemd[581]: Reloading... machine # [ 72.192552] systemd[581]: Reloading finished in 110 ms. machine # [ 72.208166] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 72.219214] sudo[4768]: 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 # [ 72.247504] sudo[4778]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service machine # [ 72.256906] sudo[4778]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 72.269358] systemd[581]: Starting migrated.service... machine # [ 72.293866] systemd[581]: Finished migrated.service. machine # [ 72.297725] sudo[4778]: 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 # [ 72.323422] sudo[4786]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 72.332743] sudo[4786]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 72.341884] sudo[4786]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 72.367041] sudo[4792]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 72.376800] sudo[4792]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 72.383386] sudo[4792]: 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 # [ 72.408707] sudo[4798]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 72.418000] sudo[4798]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 72.433201] sudo[4798]: 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/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 72.624696] nixos[4807]: switching to system configuration /nix/store/dyva96i4sypfa7gqsq2nw0np5sb9w6p4-nixos-system-machine-test machine # [ 72.628552] systemd[1]: Stopped target Local File Systems. machine # [ 72.629375] systemd[1]: Stopped target Remote File Systems. machine # [ 72.876421] systemd[1]: Reload requested from client PID 4807 ('.switch-to-conf') (unit backdoor.service)... machine # [ 72.878237] systemd[1]: Reloading... machine # [ 73.176803] systemd[1]: Reloading finished in 297 ms. machine # [ 73.259694] systemd[581]: Reexecution requested from client PID 4876 ('switch-to-confi')... machine # [ 73.262546] systemd[581]: Reexecuting. machine # [ 73.327902] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 73.446842] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 73.452946] systemd[581]: Reload requested from client PID 4876 ('switch-to-confi')... machine # [ 73.455159] systemd[581]: Reloading... machine # [ 73.563644] systemd[581]: Reloading finished in 107 ms. machine # [ 73.580156] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 73.589620] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 73.590601] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 73.593256] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 73.622654] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 73.624600] systemd[581]: Reload requested from client PID 4876 ('switch-to-confi')... machine # [ 73.628193] systemd[581]: Reloading... machine # [ 73.735457] systemd[581]: Reloading finished in 108 ms. machine # [ 73.752149] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 73.763622] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 73.766934] systemd[1]: Stopping Reactivate sysinit units... machine # [ 73.767754] systemd[1]: Reached target Reactivate sysinit units. machine # [ 73.769274] systemd[1]: Reached target Remote File Systems. machine # [ 73.774528] systemd[1]: Starting linger-users.service... machine # [ 73.782250] systemd[1]: Reached target Local File Systems. machine # [ 73.786535] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 73.794020] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 73.794907] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 73.802992] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 73.808189] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 73.811583] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 73.815255] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 73.818464] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 73.821909] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 73.853564] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 73.855926] systemd[1]: Finished linger-users.service. machine # [ 73.987004] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 73.989028] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 74.248163] nixos[4807]: finished switching to system configuration /nix/store/dyva96i4sypfa7gqsq2nw0np5sb9w6p4-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.83 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 74.318828] sudo[4948]: 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 # [ 74.338845] sudo[4948]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 74.364092] sudo[4948]: 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.11 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 74.402982] sudo[4954]: 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 # [ 74.415073] sudo[4954]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 74.423440] sudo[4954]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds) machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd machine # [ 74.451053] sudo[4960]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd machine # [ 74.460595] sudo[4960]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 74.468690] sudo[4960]: 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 # [ 74.494217] sudo[4966]: 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 # [ 74.503254] sudo[4966]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 74.511312] systemd[581]: Reload requested from client PID 4971 ('systemctl')... machine # [ 74.513471] systemd[581]: Reloading... machine # [ 74.626544] systemd[581]: Reloading finished in 111 ms. machine # [ 74.640154] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 74.649721] sudo[4966]: 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 # [ 74.676682] sudo[4976]: 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 # [ 74.685754] sudo[4976]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 74.696427] systemd[581]: Stopped migrated.service. machine # [ 74.699797] sudo[4976]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service, in 0.05 seconds) machine: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 74.889610] nixos[4985]: switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test machine # [ 74.892225] systemd[1]: Stopped target Local File Systems. machine # [ 74.894347] systemd[1]: Stopped target Remote File Systems. machine # [ 75.142308] systemd[1]: Reload requested from client PID 4985 ('.switch-to-conf') (unit backdoor.service)... machine # [ 75.144250] systemd[1]: Reloading... machine # [ 75.447984] systemd[1]: Reloading finished in 302 ms. machine # [ 75.532242] systemd[581]: Reexecution requested from client PID 5054 ('switch-to-confi')... machine # [ 75.533623] systemd[581]: Reexecuting. machine # [ 75.587064] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 75.699217] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 75.706148] systemd[581]: Reload requested from client PID 5054 ('switch-to-confi')... machine # [ 75.707440] systemd[581]: Reloading... machine # [ 75.814326] systemd[581]: Reloading finished in 105 ms. machine # [ 75.828154] systemd[581]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 75.836734] systemd[581]: Stopped Run user-specific NixOS activation. machine # [ 75.840862] systemd[581]: Stopping Run user-specific NixOS activation... machine # [ 75.841825] systemd[581]: Starting Run user-specific NixOS activation... machine # [ 75.870310] systemd[581]: Finished Run user-specific NixOS activation. machine # [ 75.873906] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 75.876517] systemd[1]: Stopping Reactivate sysinit units... machine # [ 75.877329] systemd[1]: Reached target Reactivate sysinit units. machine # [ 75.881824] systemd[1]: Reached target Local File Systems. machine # [ 75.884815] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 75.888801] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 75.889621] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 75.890828] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 75.902396] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 75.903926] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 75.908319] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 75.909525] systemd[1]: Reached target Remote File Systems. machine # [ 75.910284] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 75.911489] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 76.062242] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 76.066840] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 76.068683] systemd[1]: Starting linger-users.service... machine # [ 76.107149] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 76.110130] systemd[1]: Finished linger-users.service. machine # [ 76.366640] nixos[4985]: finished switching to system configuration /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test machine: (finished: must succeed: /nix/store/l32hqzg7idfsqygbnxr3xbrg5wbsclys-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.68 seconds) (finished: subtest: user services, in 50.90 seconds) (finished: run the VM test script, in 77.23 seconds) test script finished in 77.29s cleanup kill QemuMachine (pid 45) machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14) machine # [2026-09-23T03:01:06Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-23T03:01:06Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-23T03:01:06Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.17 seconds) additionally exposed symbols: machine, other, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh