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.knM9tWyTcK', 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: 7768744b-c166-4270-9619-55b07a67f79a machine # Superblock backups stored on blocks: machine # 32768, 98304, 163840, 229376 machine # machine # Allocating group tables: 0/8 done machine # Writing inode tables: 0/8 done machine # Creating journal (8192 blocks): done machine # Writing superblocks and filesystem accounting information: 0/8 done machine # machine # Virtualisation disk image created. machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40] machine # [ 0.000000] Linux version 6.18.48 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Aug 28 06:22:54 UTC 2026 machine # [ 0.000000] KASLR enabled machine # [ 0.000000] random: crng init done machine # [ 0.000000] Machine model: linux,dummy-virt machine # [ 0.000000] efi: UEFI not found. machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f] machine # [ 0.000000] Zone ranges: machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] DMA32 empty machine # [ 0.000000] Normal empty machine # [ 0.000000] Device empty machine # [ 0.000000] Movable zone start for each node machine # [ 0.000000] Early memory node ranges machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000 machine # [ 0.000000] psci: probing for conduit method from DT. machine # [ 0.000000] psci: PSCIv1.3 detected in firmware. machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs machine # [ 0.000000] psci: Trusted OS migration not required machine # [ 0.000000] psci: SMC Calling Convention v1.1 machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u311296 machine # [ 0.000000] Detected PIPT I-cache on CPU0 machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm) machine # [ 0.000000] CPU features: detected: GICv3 CPU interface machine # [ 0.000000] CPU features: detected: Spectre-v4 machine # [ 0.000000] CPU features: detected: Spectre-BHB machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38 machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23 machine # [ 0.000000] alternatives: applying boot alternatives machine # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/init regInfo=/nix/store/p2bdcc7gqfh9cdgwwpllc4fq730xhhz0-closure-info/registration console=ttyAMA0,115200n8 console=tty0 machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/p2bdcc7gqfh9cdgwwpllc4fq730xhhz0-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 74886 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 @0x0000000044d00000 machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000 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.000029] arm-pv: using stolen time PV machine # [ 0.000468] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.000628] Console: colour dummy device 80x25 machine # [ 0.000636] printk: legacy console [tty0] enabled machine # [ 0.000828] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) machine # [ 0.000835] pid_max: default: 32768 minimum: 301 machine # [ 0.000916] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.001047] landlock: Up and running. machine # [ 0.001050] Yama: becoming mindful. machine # [ 0.001516] LSM support for eBPF active machine # [ 0.001691] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.001710] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.002808] cacheinfo: Unable to detect cache hierarchy for CPU 0 machine # [ 0.003653] rcu: Hierarchical SRCU implementation. machine # [ 0.003659] rcu: Max phase no-delay instances is 1000. machine # [ 0.004947] fsl-mc MSI: its@8080000 domain created machine # [ 0.005039] EFI services will not be available. machine # [ 0.005125] smp: Bringing up secondary CPUs ... machine # [ 0.005134] smp: Brought up 1 node, 1 CPU machine # [ 0.005137] SMP: Total of 1 processors activated. machine # [ 0.005140] CPU: All CPU(s) started at EL1 machine # [ 0.005155] CPU features: detected: Branch Target Identification machine # [ 0.005160] CPU features: detected: ARMv8.4 Translation Table Level machine # [ 0.005163] CPU features: detected: Instruction cache invalidation not required for I/D coherence machine # [ 0.005166] CPU features: detected: Data cache clean to the PoU not required for I/D coherence machine # [ 0.005170] CPU features: detected: Common not Private translations machine # [ 0.005173] CPU features: detected: CRC32 instructions machine # [ 0.005176] CPU features: detected: Data cache clean to Point of Deep Persistence machine # [ 0.005179] CPU features: detected: Data cache clean to Point of Persistence machine # [ 0.005182] CPU features: detected: Data independent timing control (DIT) machine # [ 0.005186] CPU features: detected: E0PD machine # [ 0.005188] CPU features: detected: Enhanced Counter Virtualization machine # [ 0.005192] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) machine # [ 0.005195] CPU features: detected: Enhanced Virtualization Traps machine # [ 0.005198] CPU features: detected: Fine Grained Traps machine # [ 0.005202] CPU features: detected: Generic authentication (architected QARMA5 algorithm) machine # [ 0.005207] CPU features: detected: RCpc load-acquire (LDAPR) machine # [ 0.005210] CPU features: detected: LSE atomic instructions machine # [ 0.005213] CPU features: detected: Privileged Access Never machine # [ 0.005216] CPU features: detected: PMUv3 machine # [ 0.005218] CPU features: detected: RAS Extension Support machine # [ 0.005221] CPU features: detected: RASv1p1 Extension Support machine # [ 0.005223] CPU features: detected: Random Number Generator machine # [ 0.005226] CPU features: detected: Speculation barrier (SB) machine # [ 0.005229] CPU features: detected: Stage-2 Force Write-Back machine # [ 0.005232] CPU features: detected: TLB range maintenance instructions machine # [ 0.005237] CPU features: detected: Speculative Store Bypassing Safe (SSBS) machine # [ 0.005274] alternatives: applying system-wide alternatives machine # [ 0.008338] CPU features: detected: BBM Level 2 without TLB conflict abort machine # [ 0.008555] Memory: 894712K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 112596K reserved, 32768K cma-reserved) machine # [ 0.008921] devtmpfs: initialized machine # [ 0.010672] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.010694] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). machine # [ 0.010871] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL machine # [ 0.010876] 0 pages in range for non-PLT usage machine # [ 0.010877] 508288 pages in range for PLT usage machine # [ 0.010991] pinctrl core: initialized pinctrl subsystem machine # [ 0.011820] DMI not present or invalid. machine # [ 0.015208] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.017539] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.017685] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.017848] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.017872] audit: initializing netlink subsys (disabled) machine # [ 0.018432] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.018434] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.018437] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.018440] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.018446] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.018503] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 machine # [ 0.018513] cpuidle: using governor ladder machine # [ 0.018519] cpuidle: using governor menu machine # [ 0.019322] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers. machine # [ 0.019346] ASID allocator initialised with 65536 entries machine # [ 0.020582] Serial: AMBA PL011 UART driver machine # [ 0.025882] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 machine # [ 0.026055] printk: console [ttyAMA0] enabled machine # [ 0.150287] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.150312] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.150317] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages machine # [ 0.150322] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page machine # [ 0.150326] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.150331] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.150335] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages machine # [ 0.150339] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page machine # [ 0.158037] fbcon: Taking over console machine # [ 0.158058] ACPI: Interpreter disabled. machine # [ 0.166630] iommu: Default domain type: Translated machine # [ 0.166647] iommu: DMA domain TLB invalidation policy: strict mode machine # [ 0.167131] SCSI subsystem initialized machine # [ 0.169118] usbcore: registered new interface driver usbfs machine # [ 0.169153] usbcore: registered new interface driver hub machine # [ 0.169170] usbcore: registered new device driver usb machine # [ 0.169461] pps_core: LinuxPPS API ver. 1 registered machine # [ 0.169467] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti machine # [ 0.169478] PTP clock support registered machine # [ 0.169528] EDAC MC: Ver: 3.0.0 machine # [ 0.174173] scmi_core: SCMI protocol bus registered machine # [ 0.175247] FPGA manager framework machine # [ 0.176237] vgaarb: loaded machine # [ 0.176910] clocksource: Switched to clocksource arch_sys_counter machine # [ 0.181895] VFS: Disk quotas dquot_6.6.0 machine # [ 0.181943] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.183660] netfs: FS-Cache loaded machine # [ 0.183773] pnp: PnP ACPI: disabled machine # [ 0.187802] NET: Registered PF_INET protocol family machine # [ 0.187960] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.218887] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.218945] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.218975] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.219027] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.219105] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.219225] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.219290] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.219354] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.219450] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.219489] NET: Registered PF_XDP protocol family machine # [ 0.219510] PCI: CLS 0 bytes, default 64 machine # [ 0.219798] Trying to unpack rootfs image as initramfs... machine # [ 0.234722] kvm [1]: HYP mode not available machine # [ 0.377519] Initialise system trusted keyrings machine # [ 0.378298] workingset: timestamp_bits=42 max_order=18 bucket_order=0 machine # [ 0.385438] squashfs: version 4.0 (2009/01/31) Phillip Lougher machine # [ 0.386289] 9p: Installing v9fs 9p2000 file system support machine # [ 0.406482] Key type asymmetric registered machine # [ 0.406512] Asymmetric key parser 'x509' registered machine # [ 0.406598] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) machine # [ 0.408784] io scheduler mq-deadline registered machine # [ 0.408796] io scheduler kyber registered machine # [ 0.422481] pl061_gpio 9030000.pl061: PL061 GPIO chip registered machine # [ 0.423934] ledtrig-cpu: registered to indicate activity on CPUs machine # [ 0.424335] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: machine # [ 0.424353] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 machine # [ 0.424365] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 machine # [ 0.424374] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 machine # [ 0.424394] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits machine # [ 0.424416] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] machine # [ 0.424501] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 machine # [ 0.424510] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.424517] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] machine # [ 0.424523] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] machine # [ 0.424527] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] machine # [ 0.424586] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.437189] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.437379] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.437397] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.437428] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.437445] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.437899] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.438082] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.438098] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.438128] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.438587] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.438770] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.438787] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.438818] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.439293] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.439473] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.439489] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.439519] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.439978] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint machine # [ 0.440161] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.440177] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.440208] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.440655] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.440839] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] machine # [ 0.440855] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.440886] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.469548] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.469773] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.469792] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.469823] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.469839] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] machine # [ 0.470301] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint machine # [ 0.470489] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.470520] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.470985] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.471200] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.471232] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.471621] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.471806] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] machine # [ 0.472064] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.472354] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] machine # [ 0.472372] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.472402] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.472851] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.488134] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] machine # [ 0.488156] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] machine # [ 0.488186] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] machine # [ 0.488793] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned machine # [ 0.488805] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned machine # [ 0.488811] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned machine # [ 0.488856] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned machine # [ 0.488903] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned machine # [ 0.495923] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned machine # [ 0.495975] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned machine # [ 0.496022] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned machine # [ 0.496071] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned machine # [ 0.496119] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned machine # [ 0.496166] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned machine # [ 0.496213] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned machine # [ 0.496307] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned machine # [ 0.496355] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned machine # [ 0.496377] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned machine # [ 0.496399] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned machine # [ 0.496421] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned machine # [ 0.496444] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned machine # [ 0.496469] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned machine # [ 0.496491] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned machine # [ 0.496513] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned machine # [ 0.496535] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned machine # [ 0.496557] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned machine # [ 0.496579] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned machine # [ 0.496602] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned machine # [ 0.496624] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned machine # [ 0.496645] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned machine # [ 0.496666] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned machine # [ 0.496688] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned machine # [ 0.496709] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned machine # [ 0.496731] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned machine # [ 0.496753] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned machine # [ 0.496775] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned machine # [ 0.496798] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned machine # [ 0.496825] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] machine # [ 0.496834] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] machine # [ 0.496839] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] machine # [ 0.530129] pci 0000:00:0a.0: enabling device (0000 -> 0002) machine # [ 0.551485] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) machine # [ 0.561668] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) machine # [ 0.573244] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) machine # [ 0.575507] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) machine # [ 0.577593] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) machine # [ 0.579890] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) machine # [ 0.590478] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) machine # [ 0.592727] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) machine # [ 0.604311] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) machine # [ 0.610609] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) machine # [ 0.621207] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) machine # [ 0.634405] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.636351] msm_serial: driver initialized machine # [ 0.636521] SuperH (H)SCI(F) driver initialized machine # [ 0.636576] STM32 USART driver initialized machine # [ 0.671083] loop: module loaded machine # [ 0.671360] virtio_blk virtio5: 1/0/0 default/read/poll queues machine # [ 0.672242] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 0.685782] megasas: 07.734.00.00-rc1 machine # [ 0.686536] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] machine # [ 0.688997] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.689032] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.690605] Using buffer write method machine # [ 0.690708] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] machine # [ 0.692437] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 machine # [ 0.692477] Intel/Sharp Extended Query Table at 0x0031 machine # [ 0.711193] Using buffer write method machine # [ 0.711252] Concatenating MTD devices: machine # [ 0.711257] (0): "0.flash" machine # [ 0.711261] (1): "0.flash" machine # [ 0.711265] into device "0.flash" machine # [ 0.992339] Freeing initrd memory: 25736K machine # [ 0.998835] tun: Universal TUN/TAP device driver, 1.6 machine # [ 1.002777] thunder_xcv, ver 1.0 machine # [ 1.002826] thunder_bgx, ver 1.0 machine # [ 1.002848] nicpf, ver 1.0 machine # [ 1.003439] e1000: Intel(R) PRO/1000 Network Driver machine # [ 1.003446] e1000: Copyright (c) 1999-2006 Intel Corporation. machine # [ 1.003478] e1000e: Intel(R) PRO/1000 Network Driver machine # [ 1.003486] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. machine # [ 1.003510] igb: Intel(R) Gigabit Ethernet Network Driver machine # [ 1.003515] igb: Copyright (c) 2007-2014 Intel Corporation. machine # [ 1.003536] igbvf: Intel(R) Gigabit Virtual Function Network Driver machine # [ 1.003542] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. machine # [ 1.003676] sky2: driver version 1.30 machine # [ 1.005596] ehci-pci 0000:00:0a.0: EHCI Host Controller machine # [ 1.005627] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 machine # [ 1.005870] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 machine # [ 1.015670] usbcore: registered new interface driver usb-storage machine # [ 1.015739] usbcore: registered new interface driver usbserial_generic machine # [ 1.015753] usbserial: USB Serial support registered for generic machine # [ 1.016383] hv_vmbus: registering driver hyperv_keyboard machine # [ 1.016982] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 machine # [ 1.017367] hub 1-0:1.0: USB hub found machine # [ 1.017389] hub 1-0:1.0: 6 ports detected machine # [ 1.022701] rtc-pl031 9010000.pl031: registered as rtc0 machine # [ 1.022730] rtc-pl031 9010000.pl031: setting system clock to 2026-09-01T03:10:37 UTC (1788232237) machine # [ 1.023098] i2c_dev: i2c /dev entries driver machine # [ 1.028274] sdhci: Secure Digital Host Controller Interface driver machine # [ 1.028293] sdhci: Copyright(c) Pierre Ossman machine # [ 1.028565] Synopsys Designware Multimedia Card Interface Driver machine # [ 1.031011] sdhci-pltfm: SDHCI platform and OF driver helper machine # [ 1.032870] hid: raw HID events driver (C) Jiri Kosina machine # [ 1.034486] usbcore: registered new interface driver usbhid machine # [ 1.034497] usbhid: USB HID core driver machine # [ 1.036860] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available machine # [ 1.039559] drop_monitor: Initializing network drop monitor service machine # [ 1.039778] NET: Registered PF_INET6 protocol family machine # [ 1.041993] Segment Routing with IPv6 machine # [ 1.042016] In-situ OAM (IOAM) with IPv6 machine # [ 1.042046] NET: Registered PF_PACKET protocol family machine # [ 1.043713] 9pnet: Installing 9P2000 support machine # [ 1.046662] Key type dns_resolver registered machine # [ 1.053441] registered taskstats version 1 machine # [ 1.053616] Loading compiled-in X.509 certificates machine # [ 1.062593] Demotion targets for Node 0: null machine # [ 1.062723] Key type .fscrypt registered machine # [ 1.062730] Key type fscrypt-provisioning registered machine # [ 1.062839] ima: No TPM chip found, activating TPM-bypass! machine # [ 1.062862] ima: Allocated hash algorithm: sha1 machine # [ 1.062887] ima: No architecture policies found machine # [ 1.067286] input: gpio-keys as /devices/platform/gpio-keys/input/input0 machine # [ 1.086895] clk: Disabling unused clocks machine # [ 1.086933] PM: genpd: Disabling unused power domains machine # [ 1.091355] Freeing unused kernel memory: 4736K machine # [ 1.091587] Run /init as init process machine # [ 1.107489] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.265016] usb 1-1: new high-speed USB device number 2 using ehci-pci machine # [ 1.419581] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 machine # [ 1.442510] systemd[1]: systemd 262-d0fcc5a 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.454780] systemd[1]: Detected virtualization qemu. machine # [ 1.456968] systemd[1]: Detected architecture arm64. machine # [ 1.458903] systemd[1]: Running in initrd. machine # [ 1.461630] systemd[1]: Initializing machine ID from random generator. machine # [ 1.464606] systemd[1]: Hostname set to . machine # [ 1.509252] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0 machine # [ 1.617351] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.633032] usb 1-2: new high-speed USB device number 3 using ehci-pci machine # [ 1.674927] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.683137] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.684236] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.685503] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.686373] systemd[1]: Reached target Path Units. machine # [ 1.687041] systemd[1]: Reached target Slice Units. machine # [ 1.687741] systemd[1]: Reached target Swaps. machine # [ 1.688355] systemd[1]: Reached target Timer Units. machine # [ 1.689237] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.690303] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.691303] systemd[1]: Listening on Journal Sockets. machine # [ 1.692111] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.692953] systemd[1]: Reached target Socket Units. machine # [ 1.695284] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.701990] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 1.702967] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 1.713294] systemd[1]: Mounting Kernel Configuration File System... machine # [ 1.745328] systemd[1]: Starting Journal Service... machine # [ 1.753588] systemd[1]: Starting Load Kernel Modules... machine # [ 1.754446] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.761169] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.781859] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.784627] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 1.789108] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 1.789677] systemd[1]: Mounted Kernel Configuration File System. machine # [ 1.805801] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.807657] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 machine # [ 1.807829] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 machine # [ 1.830111] systemd-journald[73]: Collecting audit messages is disabled. machine # [ 1.844403] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.854598] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.873254] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 machine # [ 1.873528] [drm] features: -virgl +edid -resource_blob -host_visible machine # [ 1.873543] [drm] features: -context_init machine # [ 1.874327] [drm] number of scanouts: 1 machine # [ 1.874349] [drm] number of cap sets: 0 machine # [ 1.885882] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.889003] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic machine # [ 1.889026] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 machine # [ 1.901665] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.953048] Console: switching to colour frame buffer device 160x50 machine # [ 1.953751] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device machine # [ 1.967219] systemd[1]: Finished Load Kernel Modules. machine # [ 1.970991] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.989243] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.992235] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.994574] systemd[1]: Reached target Local File Systems. machine # [ 2.001411] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 2.012551] systemd-modules-load[74]: Inserted module 'dm_mod' machine # [ 2.030745] systemd[1]: Started Journal Service. machine # [ 2.024294] systemd-modules-load[74]: Module 'virtio_balloon' is built in machine # [ 2.032171] systemd-modules-load[74]: Module 'virtio_console' is built in machine # [ 2.036566] systemd-modules-load[74]: Inserted module 'virtio_gpu' machine # [ 2.037669] systemd-modules-load[74]: Module 'virtio_rng' is built in machine # [ 2.038721] systemd[1]: Starting Create System Files and Directories... machine # [ 2.057455] systemd[1]: Finished Apply Kernel Variables. machine # [ 2.093679] systemd[1]: Finished Create System Files and Directories. machine # [ 2.105254] systemd-udevd[80]: Using default interface naming scheme 'v261'. machine # [ 2.138789] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 2.220382] systemd[1]: Starting Virtual Console Setup... machine # [ 2.300648] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.304614] systemd[1]: Finished Virtual Console Setup. machine # [ 2.917997] systemd[1]: Finished Coldplug All udev Devices. machine # [ 2.919769] systemd[1]: Reached target System Initialization. machine # [ 2.920774] systemd[1]: Reached target Basic System. machine # [ 3.077460] (udev-worker)[94]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 3.091557] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line. machine # [ 3.116394] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. machine # [ 3.184157] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 3.189154] systemd[1]: Reached target Initrd Root Device. machine # [ 3.193897] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 3.255548] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 3.267392] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. machine # [ 3.272232] systemd[1]: Mounting /sysroot... machine # [ 3.326084] EXT4-fs (vda): mounted filesystem 7768744b-c166-4270-9619-55b07a67f79a r/w with ordered data mode. Quota mode: none. machine # [ 3.317328] systemd[1]: Mounted /sysroot. machine # [ 3.319019] systemd[1]: Reached target Initrd Root File System. machine # [ 3.324147] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 3.356525] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 3.361231] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 3.364779] systemd[1]: Reloading... machine # [ 3.581704] systemd[1]: Reloading finished in 217 ms. machine # [ 3.614679] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace... machine # [ 3.617395] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace... machine # [ 3.629379] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 3.632226] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 3.634108] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 3.640308] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 3.663527] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 3.666296] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 3.758191] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 3.771112] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 3.783206] systemd[1]: Mounting /sysroot/run... machine # [ 3.804310] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 3.851052] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 3.895946] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 3.899410] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 3.909245] systemd[1]: Mounted /sysroot/run. machine # [ 3.911176] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 3.928712] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.930952] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 3.965325] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.968124] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 4.595469] (udev-worker)[94]: mtd0ro: Failed to find and pin callout binary "/nix/store/aap1fb66p72ib35mngb3ccjh9halipzf-systemd-262-d0fcc5a/lib/udev/mtd_probe": No such file or directory machine # [ 4.599346] (udev-worker)[94]: mtd0ro: /nix/store/aap1fb66p72ib35mngb3ccjh9halipzf-systemd-262-d0fcc5a/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory machine # [ 4.634368] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.635572] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.640319] systemd[1]: Stopping Virtual Console Setup... machine # [ 4.641185] systemd[1]: Starting Virtual Console Setup... machine # [ 4.657481] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.664323] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.668212] systemd[1]: Starting Virtual Console Setup... machine # [ 4.695415] systemd-vconsole-setup[155]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 4.699028] systemd[1]: Finished Virtual Console Setup. machine # [ 4.758425] systemd[1]: Mounting /sysroot/nix/store... machine # [ 4.795437] systemd[1]: Mounted /sysroot/nix/store. machine # [ 4.797583] systemd[1]: Reached target Initrd File Systems. machine # [ 4.802438] systemd[1]: Starting Find NixOS closure... machine # [ 4.811957] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 4.845060] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 4.849252] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. machine # [ 4.863108] systemd[1]: Finished Find NixOS closure. machine # [ 4.866889] systemd[1]: Reached target Initrd Default Target. machine # [ 4.872709] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 4.900282] systemd[1]: Stopped target Initrd Default Target. machine # [ 4.902030] systemd[1]: Stopped target Basic System. machine # [ 4.903020] systemd[1]: Stopped target Initrd Root Device. machine # [ 4.907398] systemd[1]: Stopped target Path Units. machine # [ 4.908383] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 4.909424] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 4.910505] systemd[1]: Stopped target Slice Units. machine # [ 4.911228] systemd[1]: Stopped target Socket Units. machine # [ 4.911894] systemd[1]: Stopped target System Initialization. machine # [ 4.916352] systemd[1]: Stopped target Swaps. machine # [ 4.917566] systemd[1]: Stopped target Timer Units. machine # [ 4.920212] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 4.921127] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 4.923277] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 4.928245] systemd[1]: Stopped Find NixOS closure. machine # [ 4.930387] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 4.934912] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 4.941708] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 4.947433] systemd[1]: Stopped Apply Kernel Variables. machine # [ 4.951638] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 4.956489] systemd[1]: Stopped Load Kernel Modules. machine # [ 4.958839] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 4.961081] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 4.962381] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 4.963423] systemd[1]: Stopped Create System Files and Directories. machine # [ 4.970551] systemd[1]: Stopped target Local File Systems. machine # [ 4.976377] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 4.979078] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 4.982999] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 4.984602] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 4.987680] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 4.992377] systemd[1]: Stopped Virtual Console Setup. machine # [ 4.993178] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 4.994116] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 4.995056] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 4.995977] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 5.000209] systemd[1]: systemd-udevd.service: Consumed 1.581s CPU time over 2.985s wall clock time, 22M memory peak. machine # [ 5.002389] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 5.003567] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 5.004650] systemd[1]: Starting Cleanup udev Database... machine # [ 5.005435] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 5.006543] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 5.007426] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 5.013660] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 5.016248] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 5.020444] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 5.021671] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 5.022758] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 5.055709] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 5.059718] systemd[1]: Finished Cleanup udev Database. machine # [ 5.061138] systemd[1]: Reached target Switch Root. machine # [ 5.062481] systemd[1]: Starting NixOS Activation... machine # [ 5.269587] initrd-nixos-activation-start[180]: booting system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test machine # [ 5.343105] initrd-nixos-activation-start[180]: running activation script... machine # [ 5.789346] initrd-nixos-activation-start[203]: setting up /etc... machine # [ 6.070478] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 6.073586] systemd[1]: Finished NixOS Activation. machine # [ 6.076256] systemd[1]: Starting Switch Root... machine # [ 6.102210] systemd[1]: Switching root. machine # [ 6.299076] systemd-journald[73]: Received SIGTERM from PID 1 (systemd). machine # [ 6.900081] systemd[1]: systemd 262-d0fcc5a 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.913655] systemd[1]: Detected virtualization qemu. machine # [ 6.917989] systemd[1]: Detected architecture arm64. machine # [ 6.920264] systemd[1]: Detected first boot. machine # [ 6.928617] systemd[1]: Initializing machine ID from random generator. machine # [ 7.130083] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 7.314573] systemd[1]: Applying preset policy. machine # [ 7.801558] systemd[1]: Populated /etc with preset unit settings. machine # [ 8.346908] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 8.348454] systemd[1]: Stopped initrd-switch-root.service. machine # [ 8.352438] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 8.355579] systemd[1]: Created slice Slice /system/getty. machine # [ 8.357671] systemd[1]: Created slice User and Session Slice. machine # [ 8.359266] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 8.361404] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 8.364036] systemd[1]: Expecting device /dev/hvc0... machine # [ 8.365251] systemd[1]: Expecting device /dev/ttyAMA0... machine # [ 8.367777] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 8.369111] systemd[1]: Stopped target initrd-fs.target. machine # [ 8.370665] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 8.372209] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 8.374365] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 8.376448] systemd[1]: Reached target Path Units. machine # [ 8.378362] systemd[1]: Reached target Remote File Systems. machine # [ 8.380285] systemd[1]: Reached target Slice Units. machine # [ 8.382409] systemd[1]: Reached target Swaps. machine # [ 8.387860] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 8.395770] systemd[1]: Listening on Process Core Dump Socket. machine # [ 8.400271] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 8.405434] systemd[1]: Listening on Factory Reset Management. machine # [ 8.406961] systemd[1]: Listening on Hostname Service Socket. machine # [ 8.413894] systemd[1]: Starting Journal Log Access Socket... machine # [ 8.416874] systemd[1]: Listening on Journal Audit Socket. machine # [ 8.422333] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 8.424653] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 8.426841] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.429714] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 8.442663] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 8.445556] systemd[1]: Listening on udev Varlink Socket. machine # [ 8.452046] systemd[1]: Mounting Huge Pages File System... machine # [ 8.459798] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 8.468067] systemd[1]: Mounting Kernel Debug File System... machine # [ 8.484616] systemd[1]: Mounting Kernel Trace File System... machine # [ 8.500143] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 8.513897] systemd[1]: Starting Load Kernel Module 9pnet_virtio... machine # [ 8.515571] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 8.538262] systemd[1]: Mounting Kernel Configuration File System... machine # [ 8.540361] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 8.544333] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 8.564712] systemd[1]: Starting Load Kernel Module fuse... machine # [ 8.576703] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 8.629358] systemd[1]: Starting Journal Service... machine # [ 8.701264] systemd[1]: Starting Load Kernel Modules... machine # [ 8.712489] fuse: init (API version 7.45) machine # [ 8.724103] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 8.755616] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 8.766405] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 8.797359] systemd-journald[275]: Collecting audit messages is enabled. machine # [ 8.815054] systemd[1]: Starting Coldplug All udev Devices... machine # [ 8.804552] systemd[1]: Queued start job for default target Multi-User System. machine # [ 8.807428] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 8.832768] systemd-modules-load[276]: Module 'atkbd' is built in machine # [ 8.852490] systemd[1]: Started Journal Service. machine # [ 8.842263] systemd-modules-load[276]: Module 'loop' is built in machine # [ 8.850704] systemd[1]: Listening on Journal Log Access Socket. machine # [ 8.855578] systemd[1]: Mounted Huge Pages File System. machine # [ 8.857663] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 8.863706] systemd[1]: Mounted Kernel Debug File System. machine # [ 8.870755] systemd[1]: Mounted Kernel Trace File System. machine # [ 8.873106] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 8.893446] EXT4-fs (vda): re-mounted 7768744b-c166-4270-9619-55b07a67f79a. machine # [ 8.888407] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. machine # [ 8.889620] systemd[1]: Finished Load Kernel Module 9pnet_virtio. machine # [ 8.890583] systemd[1]: Mounted Kernel Configuration File System. machine # [ 8.891465] systemd[1]: modprobe@fuse.service: Deactivated successfully. machine # [ 8.901303] systemd[1]: Finished Load Kernel Module fuse. machine # [ 8.907955] systemd[1]: Finished Load Kernel Modules. machine # [ 8.910849] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 8.928232] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 8.940908] systemd[1]: Mounting FUSE Control File System... machine # [ 8.947998] systemd[1]: Starting Firewall... machine # [ 8.969201] systemd-oomd[277]: No swap; memory pressure usage will be degraded machine # [ 8.974165] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 8.982610] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 9.004875] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 9.030761] systemd[1]: Starting Apply Kernel Variables... machine # [ 9.069370] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 9.081121] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 9.083982] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 9.135595] systemd-journald[275]: Received client request to flush runtime journal. machine # [ 9.253577] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 9.263102] systemd[1]: Reached target First Boot Complete. machine # [ 9.280274] systemd[1]: Mounted FUSE Control File System. machine # [ 9.281916] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 9.304582] systemd[1]: Finished Apply Kernel Variables. machine # [ 9.367641] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 9.376669] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 9.507679] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 9.518718] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 9.523923] systemd[1]: Mounting /run/wrappers... machine # [ 9.533565] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 9.630017] systemd[1]: Mounted /run/wrappers. machine # [ 9.633695] systemd[1]: Reached target Local File Systems. machine # [ 9.648215] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 9.655686] systemd[1]: Starting register-nix-paths.service... machine # [ 9.662779] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 9.670123] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 9.718941] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 9.732695] systemd[1]: Starting Create System Files and Directories... machine # [ 9.756679] systemd-udevd[322]: Using default interface naming scheme 'v261'. machine # [ 9.928694] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 10.050402] systemd[1]: Finished Create System Files and Directories. machine # [ 10.063856] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 10.090257] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 10.117321] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 10.307253] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 10.332202] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 10.375950] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 10.389448] systemd[1]: Starting Update is Completed... machine # [ 10.493981] systemd[1]: Finished Update is Completed. machine # [ 10.821136] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 10.823943] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 11.389876] systemd[1]: Finished register-nix-paths.service. machine # [ 11.449306] systemd[1]: Finished Coldplug All udev Devices. machine # [ 11.451450] systemd[1]: Reached target System Initialization. machine # [ 11.457896] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 11.461370] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 11.464584] systemd[1]: Reached target Timer Units. machine # [ 11.468389] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 11.474732] systemd[1]: Listening on Nix Daemon Socket. machine # [ 11.477345] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 11.485304] systemd[1]: Reached target Socket Units. machine # [ 11.488357] systemd[1]: Reached target Basic System. machine # [ 11.490458] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 11.493536] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 11.511790] systemd[1]: Starting Post-Boot Actions... machine # [ 11.532485] systemd[1]: Started Reset console on configuration changes. machine # [ 11.580493] systemd[1]: Starting resolvconf update... machine # [ 11.628208] nsncd[468]: Sep 01 03:10:48.120 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 11.648695] systemd[1]: Starting D-Bus System Message Bus... machine # [ 11.660896] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 11.709743] systemd[1]: Finished Post-Boot Actions. machine # [ 11.738793] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 11.744385] systemd[1]: Reached target User and Group Name Lookups. machine # [ 11.754605] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs machine # [ 11.767056] systemd[1]: Starting User Login Management... machine # [ 11.825355] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 11.868652] systemd[1]: Finished Firewall. machine # [ 11.930586] dbus-broker-launch[479]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 11.940453] dbus-broker-launch[479]: NSS returned no entry for 'systemd-timesync' machine # [ 11.946059] dbus-broker-launch[479]: Invalid user-name in /nix/store/wm92fkgjxj51rc2glagdzcf6qmv17jy0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 11.982440] systemd-logind[500]: New seat seat0. machine # [ 11.990683] systemd[1]: Started User Login Management. machine # [ 12.000190] systemd[1]: Starting linger-users.service... machine # [ 12.002721] systemd[1]: Started D-Bus System Message Bus. machine # [ 12.059119] dbus-broker-launch[479]: Ready machine # [ 12.062921] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 12.069993] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 12.072045] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 12.077627] systemd[1]: Stopping User and Group Name Lookups... machine # [ 12.081498] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 12.085338] systemd[1]: nscd.service: Deactivated successfully. machine # [ 12.091189] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 12.124916] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 12.220388] systemd[1]: Created slice Slice /user/1001. machine # [ 12.237144] systemd[1]: Starting User Runtime Directory /run/user/1001... machine # [ 12.281695] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 12.304502] systemd[1]: Finished linger-users.service. machine # [ 12.323359] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 12.342574] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. machine # [ 12.363570] systemd[1]: Started backdoor.service. machine # [ 12.376695] nsncd[554]: Sep 01 03:10:48.840 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 12.409169] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 12.440722] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 12.462572] systemd[1]: Reached target User and Group Name Lookups. machine # [ 12.501851] systemd[1]: Finished User Runtime Directory /run/user/1001. machine # [ 12.528083] systemd[1]: Starting User Manager for UID 1001... machine # [ 12.613932] (udev-worker)[372]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 12.676127] (udev-worker)[371]: Network interface NamePolicy= disabled on kernel command line. machine # [ 12.700660] (systemd)[583]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 12.738749] (udev-worker)[372]: Network interface NamePolicy= disabled on kernel command line. machine # [ 12.759801] systemd[1]: Finished resolvconf update. machine # [ 12.771636] systemd[1]: Reached target Preparation for Network. machine # [ 12.779128] systemd[1]: Starting DHCP Client... machine # [ 12.797550] systemd[1]: Starting Extra networking commands.... machine # [ 12.807170] systemd-logind[500]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'. machine # [ 12.824668] systemd-logind[500]: Watching system buttons on /dev/input/event0 (gpio-keys) machine # [ 12.877911] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # connecting to host... machine: Guest shell says: b'Spawning backdoor root shell...\n' machine: connected to guest root shell machine: (connecting took 13.32 seconds) machine: (finished: waiting for the VM to finish booting, in 13.91 seconds) machine # [ 13.055716] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 13.075763] systemd[1]: Starting Address configuration of eth1... machine # [ 13.169217] systemd[583]: Queued start job for default target Main User Target. machine # [ 13.196908] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 13.198667] systemd[583]: Created slice User Application Slice. machine # [ 13.199509] systemd[583]: Started Daily Cleanup of User's Temporary Directories. machine # [ 13.252901] systemd[583]: Reached target Paths. machine # [ 13.253746] systemd[583]: Reached target Timers. machine # [ 13.301397] systemd[583]: Starting D-Bus User Message Bus Socket... machine # [ 13.324913] systemd[583]: Starting Create User Files and Directories... machine # [ 13.342561] systemd[583]: Finished Create User Files and Directories. machine # [ 13.397650] systemd[583]: Listening on D-Bus User Message Bus Socket. machine # [ 13.434717] systemd[583]: Reached target Sockets. machine # [ 13.458431] systemd[583]: Reached target Basic System. machine # [ 13.499072] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 13.520424] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 13.546001] systemd[583]: Reached target Main User Target. machine # [ 13.547317] network-addresses-eth1-start[602]: adding address 192.168.1.1/24... done machine # [ 13.571819] mousedev: PS/2 mouse device common for all mice machine # [ 13.589566] systemd[583]: Startup finished in 728ms. machine # [ 13.603538] systemd[1]: Started User Manager for UID 1001. machine # [ 13.641084] network-addresses-eth1-start[602]: adding address 2001:db8:1::1/64... done machine # [ 13.659057] systemd-logind[500]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) machine # [ 13.718872] systemd[1]: Finished Address configuration of eth1. machine # [ 13.751498] dhcpcd[607]: dhcpcd-10.3.2 starting machine # [ 13.808991] dhcpcd[684]: dev: loaded udev machine # [ 13.883643] 8021q: 802.1Q VLAN Support v1.8 machine # [ 13.884068] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 13.989874] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 14.032090] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 14.032637] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 14.038535] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 14.038900] cfg80211: failed to load regulatory.db machine # [ 14.090777] systemd[1]: Finished Extra networking commands.. machine # [ 14.091742] systemd[1]: Reached target Network. machine # [ 14.113932] systemd[1]: Starting Permit User Sessions... machine # [ 14.375023] systemd[1]: Finished Permit User Sessions. machine # [ 14.391108] systemd[1]: Started Getty on tty1. machine # [ 14.391868] systemd[1]: Reached target Login Prompts. machine # [ 14.597575] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 14.585734] dhcpcd[684]: eth0: waiting for carrier machine # [ 14.593192] dhcpcd[684]: libudev: received NULL device machine # [ 14.599859] dhcpcd[684]: libudev: received NULL device machine # [ 14.609375] dhcpcd[684]: eth0: carrier acquired machine # [ 14.663251] dhcpcd[684]: DUID 00:01:00:01:32:28:fe:bb:52:54:00:12:34:56 machine # [ 14.671178] dhcpcd[684]: eth0: IAID 00:12:34:56 machine # [ 14.671944] dhcpcd[684]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 14.683720] dhcpcd[684]: eth0: soliciting a DHCP lease machine # [ 14.690021] dhcpcd[684]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 14.690983] dhcpcd[684]: eth0: probing address 10.0.2.15/24 machine # [ 14.882436] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3 machine # [ 15.285601] systemd[1]: Starting Virtual Console Setup... machine # [ 15.324469] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 15.366234] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 15.369938] systemd[1]: Stopped Virtual Console Setup. machine # [ 15.371375] systemd[1]: Starting Virtual Console Setup... machine # [ 15.381405] systemd-logind[500]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 15.888505] systemd-vconsole-setup[724]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 15.896417] systemd[1]: Finished Virtual Console Setup. machine # [ 16.933141] dhcpcd[684]: eth0: soliciting an IPv6 router machine # [ 16.937142] dhcpcd[684]: eth0: Router Advertisement from fe80::2 machine # [ 16.939936] dhcpcd[684]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 16.943234] dhcpcd[684]: eth0: adding route to fec0::/64 machine # [ 16.945765] dhcpcd[684]: eth0: adding default route via fe80::2 machine # [ 20.293798] dhcpcd[684]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 20.298710] dhcpcd[684]: eth0: adding route to 10.0.2.0/24 machine # [ 20.302497] dhcpcd[684]: eth0: adding default route via 10.0.2.2 machine # [ 20.499155] systemd[1]: Started DHCP Client. machine # [ 20.501753] systemd[1]: Reached target Multi-User System. machine # [ 20.502810] systemd[1]: Startup finished in 1.079s (kernel) + 5.326s (initrd) + 14.095s (userspace) = 20.501s. machine: (finished: waiting for unit multi-user.target, in 21.90 seconds) machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test machine # Checking switch inhibitors... done machine # [ 21.641211] nixos[832]: switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test machine # activating the configuration... machine # [ 21.646061] systemd[1]: Stopped target Remote File Systems. machine # [ 21.647322] systemd[1]: Stopped target Local File Systems. machine # setting up /etc... machine # [ 22.173983] systemd[1]: Reload requested from client PID 832 ('.switch-to-conf') (unit backdoor.service)... machine # [ 22.176103] systemd[1]: Reloading... machine # [ 23.237498] systemd[1]: Reloading finished in 1057 ms. machine # reloading user units for usertest... machine # [ 23.292473] systemd[583]: Created slice User Core Session Slice. machine # [ 23.296478] systemd[583]: Starting D-Bus User Message Bus... machine # [ 23.371168] dbus-broker-launch[902]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/wm92fkgjxj51rc2glagdzcf6qmv17jy0-system-path/share/dbus-1/services/org.freedesktop.import1.service' machine # [ 23.374372] dbus-broker-launch[902]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/wm92fkgjxj51rc2glagdzcf6qmv17jy0-system-path/share/dbus-1/services/org.freedesktop.machine1.service' machine # [ 23.377670] dbus-broker-launch[902]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/wm92fkgjxj51rc2glagdzcf6qmv17jy0-system-path/share/dbus-1/services/org.freedesktop.portable1.service' machine # [ 23.381152] dbus-broker-launch[902]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/wm92fkgjxj51rc2glagdzcf6qmv17jy0-system-path/share/dbus-1/services/org.freedesktop.systemd1.service' machine # [ 23.385268] dbus-broker-launch[902]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored machine # [ 23.388421] dbus-broker-launch[902]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored machine # [ 23.390594] systemd[583]: Started D-Bus User Message Bus. machine # [ 23.399224] dbus-broker-launch[902]: Ready machine # [ 23.509321] systemd[583]: Reexecution requested from client PID 901 ('switch-to-confi')... machine # [ 23.513048] systemd[583]: Reexecuting. machine # [ 23.574726] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.783197] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 23.791442] systemd[583]: Reload requested from client PID 901 ('switch-to-confi')... machine # [ 23.794388] systemd[583]: Reloading... machine # [ 23.998754] systemd[583]: Reloading finished in 203 ms. machine # [ 24.013727] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 24.024078] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 24.025112] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 24.028500] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 24.096322] systemd[583]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 24.117446] systemd[1]: Starting Re-setup tmpfiles on a system that is already running.... machine # [ 24.246848] systemd[1]: Finished Re-setup tmpfiles on a system that is already running.. machine # [ 24.249547] systemd[1]: Reached target Reactivate sysinit units. machine # [ 24.252747] systemd[1]: Reached target Local File Systems. machine # [ 24.253609] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 24.261397] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 24.262398] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 24.263602] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 24.269563] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 24.272589] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.280521] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.281825] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 24.283091] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 24.290197] systemd[1]: Reached target Remote File Systems. machine # [ 24.662808] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 24.668253] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 24.671292] systemd[1]: Starting linger-users.service... machine # [ 24.739704] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 24.743264] systemd[1]: Finished linger-users.service. machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service machine # [ 25.000765] nixos[832]: finished switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test, in 4.03 seconds) machine: must succeed: rm /etc/NIXOS machine: (finished: must succeed: rm /etc/NIXOS, in 0.05 seconds) machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/hqw8f8yyb0g9dij3s0k5akh8rx3bbqh4-nixos-system-other-test/bin/switch-to-configuration test machine # this should succeed (config: /nix/store/hqw8f8yyb0g9dij3s0k5akh8rx3bbqh4-nixos-system-other-test, action: test) machine # Checking switch inhibitors... done machine # [ 25.720332] nixos[980]: switching to system configuration /nix/store/hqw8f8yyb0g9dij3s0k5akh8rx3bbqh4-nixos-system-other-test machine # [ 25.723331] systemd[1]: Stopped target Remote File Systems. machine # activating the configuration... machine # [ 25.726385] systemd[1]: Stopped target Local File Systems. machine # removing user ‘usertest’ machine # setting up /etc... machine # [ 26.320467] systemd[1]: Reload requested from client PID 980 ('.switch-to-conf') (unit backdoor.service)... machine # [ 26.322555] systemd[1]: Reloading... machine # [ 27.518363] systemd[1]: Reloading finished in 1192 ms. machine # reloading user units for usertest... machine # [ 27.693152] systemd[583]: Reexecution requested from client PID 1051 ('switch-to-confi')... machine # [ 27.697521] systemd[583]: Reexecuting. machine # [ 27.762298] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.966348] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 27.973454] systemd[583]: Reload requested from client PID 1051 ('switch-to-confi')... machine # [ 27.975794] systemd[583]: Reloading... machine # [ 28.172878] systemd[583]: Reloading finished in 195 ms. machine # [ 28.188249] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # restarting the following user units: nixos-activation.service machine # [ 28.199832] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 28.200952] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 28.204327] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 28.257627] systemd[583]: Finished Run user-specific NixOS activation. machine # restarting sysinit-reactivation.target machine # [ 28.262417] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 28.264314] systemd[1]: Stopping Reactivate sysinit units... machine # [ 28.265140] systemd[1]: Reached target Reactivate sysinit units. machine # reloading the following units: dbus-broker.service machine # [ 28.268462] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 28.269643] dbus-broker-launch[479]: Caught SIGHUP, trigger reload. machine # [ 28.290843] dbus-broker-launch[479]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 28.293186] dbus-broker-launch[479]: NSS returned no entry for 'systemd-timesync' machine # [ 28.294463] dbus-broker-launch[479]: Invalid user-name in /nix/store/2dv32al6b55cd2v2cji73v467zc5wb0q-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 28.330271] systemd[1]: Reloaded D-Bus System Message Bus. machine # restarting the following units: network-addresses-eth1.service machine # [ 28.337840] systemd[1]: Stopping Address configuration of eth1... machine # [ 28.386374] network-addresses-eth1-pre-stop[1064]: deleting address 192.168.1.1/24... done machine # [ 28.398020] network-addresses-eth1-pre-stop[1064]: deleting address 2001:db8:1::1/64... done machine # [ 28.408280] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 28.412305] systemd[1]: Stopped Address configuration of eth1. machine # [ 28.415380] systemd[1]: Starting Address configuration of eth1... machine # [ 28.490250] network-addresses-eth1-start[1069]: adding address 192.168.1.2/24... done machine # [ 28.502323] network-addresses-eth1-start[1069]: adding address 2001:db8:1::2/64... done machine # [ 28.518488] systemd[1]: Finished Address configuration of eth1. machine # [ 28.521214] systemd[1]: Reached target Remote File Systems. machine # [ 28.524472] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 28.528623] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 28.533694] systemd[1]: Starting linger-users.service... machine # [ 28.538591] systemd[1]: Reached target Local File Systems. machine # [ 28.550091] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 28.555048] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 28.556371] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 28.557767] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 28.559904] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 28.568069] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 28.570232] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 28.613744] linger-users-start[1083]: id: ‘usertest’: no such user machine # [ 28.615905] linger-users-start[1080]: Removing linger for missing user usertest machine # [ 28.627580] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 28.631982] systemd[1]: Finished linger-users.service. machine # [ 28.962460] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 28.965233] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 29.224222] nixos[980]: finished switching to system configuration /nix/store/hqw8f8yyb0g9dij3s0k5akh8rx3bbqh4-nixos-system-other-test machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/hqw8f8yyb0g9dij3s0k5akh8rx3bbqh4-nixos-system-other-test/bin/switch-to-configuration test, in 4.18 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 29.801388] nixos[1147]: switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test machine # [ 29.804189] systemd[1]: Stopped target Remote File Systems. machine # [ 29.806485] systemd[1]: Stopped target Local File Systems. machine # [ 30.279829] systemd[1]: Reload requested from client PID 1147 ('.switch-to-conf') (unit backdoor.service)... machine # [ 30.281671] systemd[1]: Reloading... machine # [ 32.478440] systemd[1]: Reloading finished in 2194 ms. machine # [ 32.655942] systemd[583]: Reexecution requested from client PID 1216 ('switch-to-confi')... machine # [ 32.658576] systemd[583]: Reexecuting. machine # [ 32.727648] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.934315] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 32.941067] systemd[583]: Reload requested from client PID 1216 ('switch-to-confi')... machine # [ 32.943362] systemd[583]: Reloading... machine # [ 33.148183] systemd[583]: Reloading finished in 203 ms. machine # [ 33.168511] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 33.180381] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 33.183277] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 33.186725] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 33.248993] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 33.254198] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 33.259617] systemd[1]: Stopping Reactivate sysinit units... machine # [ 33.261483] systemd[1]: Reached target Reactivate sysinit units. machine # [ 33.263234] dbus-broker-launch[479]: Caught SIGHUP, trigger reload. machine # [ 33.264888] systemd[1]: Reloading D-Bus System Message Bus... machine # [ 33.273094] dbus-broker-launch[479]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 33.275898] dbus-broker-launch[479]: NSS returned no entry for 'systemd-timesync' machine # [ 33.277569] dbus-broker-launch[479]: Invalid user-name in /nix/store/wm92fkgjxj51rc2glagdzcf6qmv17jy0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 33.301663] systemd[1]: Reloaded D-Bus System Message Bus. machine # [ 33.309706] systemd[1]: Stopping Address configuration of eth1... machine # [ 33.365324] network-addresses-eth1-pre-stop[1229]: deleting address 192.168.1.2/24... done machine # [ 33.377204] network-addresses-eth1-pre-stop[1229]: deleting address 2001:db8:1::2/64... done machine # [ 33.387886] systemd[1]: network-addresses-eth1.service: Deactivated successfully. machine # [ 33.389986] systemd[1]: Stopped Address configuration of eth1. machine # [ 33.395683] systemd[1]: Starting Address configuration of eth1... machine # [ 33.484979] network-addresses-eth1-start[1234]: adding address 192.168.1.1/24... done machine # [ 33.498628] network-addresses-eth1-start[1234]: adding address 2001:db8:1::1/64... done machine # [ 33.518769] systemd[1]: Finished Address configuration of eth1. machine # [ 33.523402] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.527678] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 33.532276] systemd[1]: Reached target Local File Systems. machine # [ 33.537741] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 33.546828] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 33.547888] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 33.550989] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 33.561598] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 33.563494] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.566461] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 33.567639] systemd[1]: Reached target Remote File Systems. machine # [ 33.957262] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 33.963159] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 33.967107] systemd[1]: Starting linger-users.service... machine # [ 34.050130] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 34.054358] systemd[1]: Finished linger-users.service. machine # [ 34.311391] nixos[1147]: finished switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 5.08 seconds) subtest: user services machine: waiting for unit user@1001.service machine: (finished: waiting for unit user@1001.service, in 0.26 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 35.225543] nixos[1308]: switching to system configuration /nix/store/kn21hnik8xf7xamc3jq0gmcmd7hhn1di-nixos-system-machine-test machine # [ 35.229566] systemd[1]: Stopped target Local File Systems. machine # [ 35.231219] systemd[1]: Stopped target Remote File Systems. machine # [ 35.861035] systemd[1]: Reload requested from client PID 1308 ('.switch-to-conf') (unit backdoor.service)... machine # [ 35.861585] systemd[1]: Reloading... machine # [ 37.570225] systemd[1]: Reloading finished in 1703 ms. machine # [ 37.782258] systemd[583]: Reexecution requested from client PID 1377 ('switch-to-confi')... machine # [ 37.783613] systemd[583]: Reexecuting. machine # [ 37.850751] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 38.081451] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 38.088510] systemd[583]: Reload requested from client PID 1377 ('switch-to-confi')... machine # [ 38.090921] systemd[583]: Reloading... machine # [ 38.318300] systemd[583]: Reloading finished in 225 ms. machine # [ 38.332335] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 38.345578] systemd[583]: Starting usertest.service... machine # [ 38.386581] systemd[583]: Finished usertest.service. machine # [ 38.389820] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 38.392195] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 38.395577] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 38.448669] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 38.453201] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 38.455422] systemd[1]: Stopping Reactivate sysinit units... machine # [ 38.457270] systemd[1]: Reached target Reactivate sysinit units. machine # [ 38.459489] systemd[1]: Reached target Local File Systems. machine # [ 38.462863] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 38.471255] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 38.473428] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 38.475467] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 38.484541] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 38.486203] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 38.487328] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 38.493696] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 38.495071] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 38.496282] systemd[1]: Reached target Remote File Systems. machine # [ 38.848992] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 38.852498] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 38.856822] systemd[1]: Starting linger-users.service... machine # [ 38.925105] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 38.927810] systemd[1]: Finished linger-users.service. machine # [ 39.187030] nixos[1308]: finished switching to system configuration /nix/store/kn21hnik8xf7xamc3jq0gmcmd7hhn1di-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 4.61 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 39.307956] sudo[1448]: 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 # [ 39.326470] sudo[1448]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 39.341862] sudo[1448]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.15 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 40.018578] nixos[1457]: switching to system configuration /nix/store/kn21hnik8xf7xamc3jq0gmcmd7hhn1di-nixos-system-machine-test machine # [ 40.022314] systemd[1]: Stopped target Local File Systems. machine # [ 40.023796] systemd[1]: Stopped target Remote File Systems. machine # [ 40.582349] systemd[1]: Reload requested from client PID 1457 ('.switch-to-conf') (unit backdoor.service)... machine # [ 40.584518] systemd[1]: Reloading... machine # [ 42.083814] systemd[1]: Reloading finished in 1496 ms. machine # [ 42.291125] systemd[583]: Reexecution requested from client PID 1526 ('switch-to-confi')... machine # [ 42.295344] systemd[583]: Reexecuting. machine # [ 42.363310] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.591834] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.601504] systemd[583]: Reload requested from client PID 1526 ('switch-to-confi')... machine # [ 42.604053] systemd[583]: Reloading... machine # [ 42.825654] systemd[583]: Reloading finished in 220 ms. machine # [ 42.840187] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 42.851288] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 42.852426] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 42.855780] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 42.908875] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 42.913642] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 42.915755] systemd[1]: Stopping Reactivate sysinit units... machine # [ 42.917881] systemd[1]: Reached target Reactivate sysinit units. machine # [ 42.919630] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 42.923347] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 42.926771] systemd[1]: Reached target Remote File Systems. machine # [ 42.929556] systemd[1]: Reached target Local File Systems. machine # [ 42.939374] systemd[1]: Starting linger-users.service... machine # [ 42.960308] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 42.967270] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 42.969424] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 42.972734] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 42.976188] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 42.977844] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 42.979124] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 43.062399] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 43.063511] systemd[1]: Finished linger-users.service. machine # [ 43.405139] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 43.409496] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 43.668213] nixos[1457]: finished switching to system configuration /nix/store/kn21hnik8xf7xamc3jq0gmcmd7hhn1di-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 4.33 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1 machine # [ 44.259432] nixos[1598]: switching to system configuration /nix/store/62qs5zz4zk9549sivlzmakp3bk7lr62s-nixos-system-machine-test machine # [ 44.263345] systemd[1]: Stopped target Local File Systems. machine # [ 44.264915] systemd[1]: Stopped target Remote File Systems. machine # [ 44.826753] systemd[1]: Reload requested from client PID 1598 ('.switch-to-conf') (unit backdoor.service)... machine # [ 44.829022] systemd[1]: Reloading... machine # [ 45.809619] systemd[1]: Reloading finished in 978 ms. machine # [ 45.973337] systemd[583]: Stopped usertest.service. machine # [ 45.977401] systemd[583]: Reexecution requested from client PID 1668 ('switch-to-confi')... machine # [ 45.978853] systemd[583]: Reexecuting. machine # [ 46.033809] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.241137] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.249114] systemd[583]: Reload requested from client PID 1668 ('switch-to-confi')... machine # [ 46.252219] systemd[583]: Reloading... machine # [ 46.476842] systemd[583]: Reloading finished in 224 ms. machine # [ 46.498202] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 46.509780] systemd[583]: Starting usertest.service... machine # [ 46.553112] systemd[583]: Finished usertest.service. machine # [ 46.556612] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 46.559510] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 46.562892] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 46.621807] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 46.627879] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 46.631071] systemd[1]: Stopping Reactivate sysinit units... machine # [ 46.632690] systemd[1]: Reached target Reactivate sysinit units. machine # [ 46.636354] systemd[1]: Reached target Remote File Systems. machine # [ 46.642284] systemd[1]: Starting linger-users.service... machine # [ 46.646257] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.647656] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 46.664183] systemd[1]: Reached target Local File Systems. machine # [ 46.665346] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 46.671645] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 46.675661] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 46.684897] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 46.691617] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 46.697636] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.699737] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 46.786545] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 46.787636] systemd[1]: Finished linger-users.service. machine # [ 47.103385] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 47.106398] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 47.376607] nixos[1598]: finished switching to system configuration /nix/store/62qs5zz4zk9549sivlzmakp3bk7lr62s-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 3.71 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 47.499124] sudo[1738]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 47.520511] sudo[1738]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 47.537737] sudo[1738]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.15 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1 machine # [ 48.168493] nixos[1748]: switching to system configuration /nix/store/qxm4majszi2bsddayzqxh2z61a65mwyl-nixos-system-machine-test machine # [ 48.172451] systemd[1]: Stopped target Local File Systems. machine # [ 48.174306] systemd[1]: Stopped target Remote File Systems. machine # [ 48.831235] systemd[1]: Reload requested from client PID 1748 ('.switch-to-conf') (unit backdoor.service)... machine # [ 48.831949] systemd[1]: Reloading... machine # [ 49.562768] systemd[1]: Reloading finished in 726 ms. machine # [ 49.758593] systemd[583]: Reexecution requested from client PID 1816 ('switch-to-confi')... machine # [ 49.761111] systemd[583]: Reexecuting. machine # [ 49.831868] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 50.090441] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 50.098279] systemd[583]: Reload requested from client PID 1816 ('switch-to-confi')... machine # [ 50.101062] systemd[583]: Reloading... machine # [ 50.305953] systemd[583]: Reloading finished in 203 ms. machine # [ 50.324197] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 50.332082] systemd[583]: Stopped usertest.service. machine # [ 50.334340] systemd[583]: Stopping usertest.service... machine # [ 50.337263] systemd[583]: Starting usertest.service... machine # [ 50.377001] systemd[583]: Finished usertest.service. machine # [ 50.382106] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 50.384311] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 50.387598] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 50.434956] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 50.439836] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 50.442960] systemd[1]: Stopping Reactivate sysinit units... machine # [ 50.443773] systemd[1]: Reached target Reactivate sysinit units. machine # [ 50.446366] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 50.449305] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 50.452115] systemd[1]: Reached target Remote File Systems. machine # [ 50.457757] systemd[1]: Starting linger-users.service... machine # [ 50.460896] systemd[1]: Reached target Local File Systems. machine # [ 50.473278] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 50.481826] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 50.482820] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 50.483977] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 50.486871] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 50.489370] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 50.491114] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 50.560872] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 50.566116] systemd[1]: Finished linger-users.service. machine # [ 50.854680] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 50.859603] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 51.117404] nixos[1748]: finished switching to system configuration /nix/store/qxm4majszi2bsddayzqxh2z61a65mwyl-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1, in 3.59 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 51.207469] sudo[1887]: 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 # [ 51.225984] sudo[1887]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 51.241326] sudo[1887]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.11 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1 machine # [ 51.852364] nixos[1896]: switching to system configuration /nix/store/88zw7imk4m3mgvy637694cq9mj06id90-nixos-system-machine-test machine # [ 51.855366] systemd[1]: Stopped target Local File Systems. machine # [ 51.857977] systemd[1]: Stopped target Remote File Systems. machine # [ 52.435436] systemd[1]: Reload requested from client PID 1896 ('.switch-to-conf') (unit backdoor.service)... machine # [ 52.437813] systemd[1]: Reloading... machine # [ 53.081258] systemd[1]: Reloading finished in 640 ms. machine # [ 53.255366] systemd[583]: Reexecution requested from client PID 1965 ('switch-to-confi')... machine # [ 53.257658] systemd[583]: Reexecuting. machine # [ 53.322018] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.534487] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.541027] systemd[583]: Reload requested from client PID 1965 ('switch-to-confi')... machine # [ 53.543493] systemd[583]: Reloading... machine # [ 53.746538] systemd[583]: Reloading finished in 201 ms. machine # [ 53.768216] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 53.778364] systemd[583]: Reloading usertest.service... machine # [ 53.817483] systemd[583]: Reloaded usertest.service. machine # [ 53.823078] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 53.825977] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 53.829084] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 53.875386] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 53.880441] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 53.881929] systemd[1]: Stopping Reactivate sysinit units... machine # [ 53.882790] systemd[1]: Reached target Reactivate sysinit units. machine # [ 53.889115] systemd[1]: Reached target Local File Systems. machine # [ 53.892616] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 53.897704] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 53.898676] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 53.899877] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 53.904317] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 53.906334] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.918229] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.919512] systemd[1]: Reached target Remote File Systems. machine # [ 53.921344] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 53.922702] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 54.246990] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 54.251418] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 54.254430] systemd[1]: Starting linger-users.service... machine # [ 54.316964] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 54.319320] systemd[1]: Finished linger-users.service. machine # [ 54.579227] nixos[1896]: finished switching to system configuration /nix/store/88zw7imk4m3mgvy637694cq9mj06id90-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1, in 3.34 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 54.664723] sudo[2035]: 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 # [ 54.686379] sudo[2035]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 54.706107] sudo[2035]: 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/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 55.238072] nixos[2044]: switching to system configuration /nix/store/kn21hnik8xf7xamc3jq0gmcmd7hhn1di-nixos-system-machine-test machine # [ 55.240616] systemd[1]: Stopped target Local File Systems. machine # [ 55.242796] systemd[1]: Stopped target Remote File Systems. machine # [ 55.664247] systemd[1]: Reload requested from client PID 2044 ('.switch-to-conf') (unit backdoor.service)... machine # [ 55.666316] systemd[1]: Reloading... machine # [ 56.238382] systemd[1]: Reloading finished in 570 ms. machine # [ 56.387737] systemd[583]: Stopped usertest.service. machine # [ 56.390903] systemd[583]: Reexecution requested from client PID 2113 ('switch-to-confi')... machine # [ 56.392898] systemd[583]: Reexecuting. machine # [ 56.457837] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 56.646593] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 56.653907] systemd[583]: Reload requested from client PID 2113 ('switch-to-confi')... machine # [ 56.656512] systemd[583]: Reloading... machine # [ 56.839990] systemd[583]: Reloading finished in 182 ms. machine # [ 56.856178] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 56.867148] systemd[583]: Starting usertest.service... machine # [ 56.902177] systemd[583]: Finished usertest.service. machine # [ 56.905331] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 56.907539] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 56.910277] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 56.951440] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 56.955768] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 56.958411] systemd[1]: Stopping Reactivate sysinit units... machine # [ 56.959243] systemd[1]: Reached target Reactivate sysinit units. machine # [ 56.963507] systemd[1]: Reached target Remote File Systems. machine # [ 56.966158] systemd[1]: Starting linger-users.service... machine # [ 56.978051] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 56.985327] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 56.986669] systemd[1]: Reached target Local File Systems. machine # [ 56.987449] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 56.995293] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 56.997591] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 56.999274] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 57.002113] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 57.003984] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 57.008247] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 57.062920] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 57.066373] systemd[1]: Finished linger-users.service. machine # [ 57.324956] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 57.328318] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 57.587236] nixos[2044]: finished switching to system configuration /nix/store/kn21hnik8xf7xamc3jq0gmcmd7hhn1di-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.89 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 57.671381] sudo[2183]: 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 # [ 57.690570] sudo[2183]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 57.705530] sudo[2183]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.11 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1 machine # [ 58.231276] nixos[2192]: switching to system configuration /nix/store/6xm94nc2dfb5b0gwa60rq68ryj7c6s5f-nixos-system-machine-test machine # [ 58.234308] systemd[1]: Stopped target Remote File Systems. machine # [ 58.236908] systemd[1]: Stopped target Local File Systems. machine # [ 58.755738] systemd[1]: Reload requested from client PID 2192 ('.switch-to-conf') (unit backdoor.service)... machine # [ 58.758141] systemd[1]: Reloading... machine # [ 59.363863] systemd[1]: Reloading finished in 603 ms. machine # [ 59.524453] systemd[583]: Reexecution requested from client PID 2261 ('switch-to-confi')... machine # [ 59.528114] systemd[583]: Reexecuting. machine # [ 59.577561] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.777441] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.783954] systemd[583]: Reload requested from client PID 2261 ('switch-to-confi')... machine # [ 59.786805] systemd[583]: Reloading... machine # [ 59.977207] systemd[583]: Reloading finished in 189 ms. machine # [ 59.988180] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 59.997390] systemd[583]: Reloading usertest.service... machine # [ 60.031147] systemd[583]: Reloaded usertest.service. machine # [ 60.036386] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 60.038724] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 60.041437] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 60.087322] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 60.091525] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 60.093334] systemd[1]: Stopping Reactivate sysinit units... machine # [ 60.094204] systemd[1]: Reached target Reactivate sysinit units. machine # [ 60.102081] systemd[1]: Reached target Remote File Systems. machine # [ 60.105404] systemd[1]: Starting linger-users.service... machine # [ 60.107746] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 60.119161] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 60.121152] systemd[1]: Reached target Local File Systems. machine # [ 60.122498] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 60.135700] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 60.136909] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 60.138223] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 60.140495] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 60.142072] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 60.143337] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 60.209413] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 60.211901] systemd[1]: Finished linger-users.service. machine # [ 60.478083] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 60.481206] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 60.738397] nixos[2192]: finished switching to system configuration /nix/store/6xm94nc2dfb5b0gwa60rq68ryj7c6s5f-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 3.04 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 60.823083] sudo[2331]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service machine # [ 60.841138] sudo[2331]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 60.856260] sudo[2331]: 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/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1 machine # [ 61.392473] nixos[2340]: switching to system configuration /nix/store/b2x9rdaj0226zkkkmzh4b5l9d7caxn6q-nixos-system-machine-test machine # [ 61.395549] systemd[1]: Stopped target Local File Systems. machine # [ 61.398515] systemd[1]: Stopped target Remote File Systems. machine # [ 61.893066] systemd[1]: Reload requested from client PID 2340 ('.switch-to-conf') (unit backdoor.service)... machine # [ 61.895322] systemd[1]: Reloading... machine # [ 62.482413] systemd[1]: Reloading finished in 585 ms. machine # [ 62.653387] systemd[583]: Stopped usertest.service. machine # [ 62.656640] systemd[583]: Reexecution requested from client PID 2409 ('switch-to-confi')... machine # [ 62.660362] systemd[583]: Reexecuting. machine # [ 62.726431] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.936938] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 62.944145] systemd[583]: Reload requested from client PID 2409 ('switch-to-confi')... machine # [ 62.945503] systemd[583]: Reloading... machine # [ 63.118163] systemd[583]: Reloading finished in 170 ms. machine # [ 63.136194] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 63.147614] systemd[583]: Starting usertest.service... machine # [ 63.184761] systemd[583]: usertest.service: Main process exited, code=exited, status=1/FAILURE machine # [ 63.186363] systemd[583]: usertest.service: Failed with result 'exit-code'. machine # [ 63.189058] systemd[583]: Failed to start usertest.service. machine # [ 63.192202] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 63.193287] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 63.197277] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 63.244931] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 63.249613] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 63.253003] systemd[1]: Stopping Reactivate sysinit units... machine # [ 63.254006] systemd[1]: Reached target Reactivate sysinit units. machine # [ 63.255674] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 63.259057] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 63.262063] systemd[1]: Reached target Local File Systems. machine # [ 63.267203] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 63.274339] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 63.275228] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 63.280642] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 63.284184] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 63.285798] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 63.287081] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 63.291682] systemd[1]: Reached target Remote File Systems. machine # [ 63.618099] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 63.622631] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 63.626502] systemd[1]: Starting linger-users.service... machine # [ 63.690836] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 63.693339] systemd[1]: Finished linger-users.service. machine # [ 63.949598] nixos[2340]: switching to system configuration /nix/store/b2x9rdaj0226zkkkmzh4b5l9d7caxn6q-nixos-system-machine-test failed (status 4) machine: (finished: must fail: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 3.10 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1 machine # [ 64.523403] nixos[2482]: switching to system configuration /nix/store/kn21hnik8xf7xamc3jq0gmcmd7hhn1di-nixos-system-machine-test machine # [ 64.528223] systemd[1]: Stopped target Remote File Systems. machine # [ 64.529602] systemd[1]: Stopped target Local File Systems. machine # [ 64.968767] systemd[1]: Reload requested from client PID 2482 ('.switch-to-conf') (unit backdoor.service)... machine # [ 64.970793] systemd[1]: Reloading... machine # [ 65.553463] systemd[1]: Reloading finished in 580 ms. machine # [ 65.699886] systemd[583]: Reexecution requested from client PID 2551 ('switch-to-confi')... machine # [ 65.703701] systemd[583]: Reexecuting. machine # [ 65.766018] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.961427] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 65.968262] systemd[583]: Reload requested from client PID 2551 ('switch-to-confi')... machine # [ 65.970787] systemd[583]: Reloading... machine # [ 66.158289] systemd[583]: Reloading finished in 186 ms. machine # [ 66.172158] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 66.183055] systemd[583]: Starting usertest.service... machine # [ 66.218338] systemd[583]: Finished usertest.service. machine # [ 66.221256] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 66.223442] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 66.226113] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 66.272388] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 66.276493] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 66.279136] systemd[1]: Stopping Reactivate sysinit units... machine # [ 66.280964] systemd[1]: Reached target Reactivate sysinit units. machine # [ 66.281874] systemd[1]: Reached target Local File Systems. machine # [ 66.285150] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 66.290083] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 66.290959] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 66.293885] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 66.299414] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 66.304463] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 66.305735] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 66.306983] systemd[1]: Reached target Remote File Systems. machine # [ 66.307736] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 66.315566] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 66.626743] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 66.630738] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 66.634158] systemd[1]: Starting linger-users.service... machine # [ 66.693605] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 66.696069] systemd[1]: Finished linger-users.service. machine # [ 66.953845] nixos[2482]: finished switching to system configuration /nix/store/kn21hnik8xf7xamc3jq0gmcmd7hhn1di-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 3.01 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 67.039218] sudo[2621]: 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 # [ 67.057826] sudo[2621]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 67.072613] sudo[2621]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.11 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 67.588723] nixos[2630]: switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test machine # [ 67.592464] systemd[1]: Stopped target Remote File Systems. machine # [ 67.593641] systemd[1]: Stopped target Local File Systems. machine # [ 68.030639] systemd[1]: Reload requested from client PID 2630 ('.switch-to-conf') (unit backdoor.service)... machine # [ 68.032778] systemd[1]: Reloading... machine # [ 68.609625] systemd[1]: Reloading finished in 575 ms. machine # [ 68.746877] systemd[583]: Stopped usertest.service. machine # [ 68.750397] systemd[583]: Reexecution requested from client PID 2699 ('switch-to-confi')... machine # [ 68.751702] systemd[583]: Reexecuting. machine # [ 68.817649] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.007379] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.014330] systemd[583]: Reload requested from client PID 2699 ('switch-to-confi')... machine # [ 69.016333] systemd[583]: Reloading... machine # [ 69.201585] systemd[583]: Reloading finished in 183 ms. machine # [ 69.216157] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 69.226333] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 69.227409] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 69.230273] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 69.276280] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 69.280697] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 69.281965] systemd[1]: Stopping Reactivate sysinit units... machine # [ 69.282776] systemd[1]: Reached target Reactivate sysinit units. machine # [ 69.286304] systemd[1]: Reached target Local File Systems. machine # [ 69.290004] systemd[1]: Reached target Remote File Systems. machine # [ 69.295297] systemd[1]: Starting linger-users.service... machine # [ 69.303106] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 69.306639] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 69.310501] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 69.318279] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 69.319150] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 69.320784] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 69.323501] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 69.325907] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 69.327587] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 69.384357] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 69.387378] systemd[1]: Finished linger-users.service. machine # [ 69.653788] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 69.656935] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 69.915380] nixos[2630]: finished switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.85 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service machine # [ 69.974995] sudo[2767]: 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 # [ 69.990550] sudo[2767]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 70.004645] sudo[2767]: 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.08 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 70.051326] sudo[2773]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 70.066428] sudo[2773]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 70.078911] sudo[2773]: 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.07 seconds) machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 70.123946] sudo[2779]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 70.137493] sudo[2779]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 70.151643] sudo[2779]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 70.196520] sudo[2785]: 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 # [ 70.209323] sudo[2785]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 70.221696] sudo[2785]: 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.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 70.266149] sudo[2791]: 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 # [ 70.279010] sudo[2791]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 70.289025] systemd[583]: Reload requested from client PID 2796 ('systemctl')... machine # [ 70.291685] systemd[583]: Reloading... machine # [ 70.484973] systemd[583]: Reloading finished in 191 ms. machine # [ 70.500178] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 70.511970] sudo[2791]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.29 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 70.560641] sudo[2801]: 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 # [ 70.574487] sudo[2801]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 70.592536] systemd[583]: Starting migrated.service... machine # [ 70.629874] systemd[583]: Finished migrated.service. machine # [ 70.634164] sudo[2801]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 70.680465] sudo[2809]: 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 # [ 70.693509] sudo[2809]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 70.705119] sudo[2809]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 70.752329] sudo[2815]: 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 # [ 70.766352] sudo[2815]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 70.780144] sudo[2815]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 70.827637] sudo[2821]: 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 # [ 70.840873] sudo[2821]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 70.863812] sudo[2821]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.08 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1 machine # [ 71.388738] nixos[2830]: switching to system configuration /nix/store/mqzhhdz520dc3lx5rz5iqqi61a0k8025-nixos-system-machine-test machine # [ 71.394105] systemd[1]: Stopped target Remote File Systems. machine # [ 71.396079] systemd[1]: Stopped target Local File Systems. machine # [ 71.894260] systemd[1]: Reload requested from client PID 2830 ('.switch-to-conf') (unit backdoor.service)... machine # [ 71.896393] systemd[1]: Reloading... machine # [ 72.484529] systemd[1]: Reloading finished in 585 ms. machine # [ 72.646354] systemd[583]: Reexecution requested from client PID 2899 ('switch-to-confi')... machine # [ 72.650628] systemd[583]: Reexecuting. machine # [ 72.718021] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 72.918744] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 72.925428] systemd[583]: Reload requested from client PID 2899 ('switch-to-confi')... machine # [ 72.927717] systemd[583]: Reloading... machine # [ 73.116722] systemd[583]: Reloading finished in 187 ms. machine # [ 73.132170] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 73.141321] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 73.143652] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 73.146445] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 73.251932] systemd[583]: Reload requested from client PID 2911 ('systemctl') (unit nixos-activation.service)... machine # [ 73.253626] systemd[583]: Reloading... machine # [ 73.446214] systemd[583]: Reloading finished in 191 ms. machine # [ 73.460164] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 73.487258] systemd[583]: Stopped migrated.service. machine # [ 73.500158] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 73.502876] systemd[583]: Reload requested from client PID 2899 ('switch-to-confi')... machine # [ 73.506886] systemd[583]: Reloading... machine # [ 73.695724] systemd[583]: Reloading finished in 189 ms. machine # [ 73.708145] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 73.720437] systemd[583]: Starting migrated.service... machine # [ 73.760745] systemd[583]: Finished migrated.service. machine # [ 73.764502] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 73.766380] systemd[1]: Stopping Reactivate sysinit units... machine # [ 73.771195] systemd[1]: Reached target Reactivate sysinit units. machine # [ 73.772252] systemd[1]: Reached target Local File Systems. machine # [ 73.776863] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 73.784770] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 73.785753] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 73.786987] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 73.792181] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 73.794537] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 73.795836] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 73.801425] systemd[1]: Reached target Remote File Systems. machine # [ 73.802716] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 73.804585] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 74.101092] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 74.104487] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 74.108691] systemd[1]: Starting linger-users.service... machine # [ 74.168376] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 74.170974] systemd[1]: Finished linger-users.service. machine # [ 74.430915] nixos[2830]: finished switching to system configuration /nix/store/mqzhhdz520dc3lx5rz5iqqi61a0k8025-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 3.58 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 74.520999] sudo[2981]: 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 # [ 74.539763] sudo[2981]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 74.556964] sudo[2981]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 74.609892] sudo[2987]: 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.624529] sudo[2987]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 74.650889] sudo[2987]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.09 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 74.696498] sudo[2993]: 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.708655] sudo[2993]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 74.720479] sudo[2993]: 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.07 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 75.225096] nixos[3002]: switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test machine # [ 75.227783] systemd[1]: Stopped target Local File Systems. machine # [ 75.230133] systemd[1]: Stopped target Remote File Systems. machine # [ 75.665434] systemd[1]: Reload requested from client PID 3002 ('.switch-to-conf') (unit backdoor.service)... machine # [ 75.667060] systemd[1]: Reloading... machine # [ 76.235271] systemd[1]: Reloading finished in 565 ms. machine # [ 76.374959] systemd[583]: Stopped migrated.service. machine # [ 76.378391] systemd[583]: Reexecution requested from client PID 3070 ('switch-to-confi')... machine # [ 76.379704] systemd[583]: Reexecuting. machine # [ 76.450226] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 76.637181] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 76.644287] systemd[583]: Reload requested from client PID 3070 ('switch-to-confi')... machine # [ 76.646718] systemd[583]: Reloading... machine # [ 76.831304] systemd[583]: Reloading finished in 183 ms. machine # [ 76.844183] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 76.855114] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 76.857515] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 76.860487] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 76.908434] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 76.912898] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 76.916217] systemd[1]: Stopping Reactivate sysinit units... machine # [ 76.917096] systemd[1]: Reached target Reactivate sysinit units. machine # [ 76.921050] systemd[1]: Reached target Remote File Systems. machine # [ 76.924091] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 76.925459] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 76.929471] systemd[1]: Reached target Local File Systems. machine # [ 76.932663] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 76.936955] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 76.937873] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 76.939092] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 76.942174] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 76.945863] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 76.948861] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 77.256706] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 77.260635] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 77.263683] systemd[1]: Starting linger-users.service... machine # [ 77.320617] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 77.322979] systemd[1]: Finished linger-users.service. machine # [ 77.577981] nixos[3002]: finished switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.86 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 77.634738] sudo[3139]: 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 # [ 77.648818] sudo[3139]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 77.662343] sudo[3139]: 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.08 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 77.706291] sudo[3145]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 77.718777] sudo[3145]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 77.730299] sudo[3145]: 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.07 seconds) machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 77.774220] sudo[3151]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 77.787013] sudo[3151]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 77.798999] sudo[3151]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 77.843521] sudo[3157]: 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 # [ 77.856371] sudo[3157]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 77.867965] sudo[3157]: 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.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 77.910246] sudo[3163]: 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 # [ 77.922757] sudo[3163]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 77.932656] systemd[583]: Reload requested from client PID 3168 ('systemctl')... machine # [ 77.936595] systemd[583]: Reloading... machine # [ 78.119471] systemd[583]: Reloading finished in 183 ms. machine # [ 78.132171] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 78.145950] sudo[3163]: 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.28 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 78.189387] sudo[3173]: 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 # [ 78.201925] sudo[3173]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 78.218721] systemd[583]: Starting migrated.service... machine # [ 78.256555] systemd[583]: Finished migrated.service. machine # [ 78.261036] sudo[3173]: 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.11 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 78.304077] sudo[3181]: 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 # [ 78.316733] sudo[3181]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 78.328184] sudo[3181]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 78.371936] sudo[3187]: 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 # [ 78.384686] sudo[3187]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 78.396374] sudo[3187]: 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.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 78.440304] sudo[3193]: 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 # [ 78.453224] sudo[3193]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 78.474877] sudo[3193]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.08 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1 machine # [ 78.981718] nixos[3202]: switching to system configuration /nix/store/13l69miwn0hkcfqwzj9kj9c27zww979i-nixos-system-machine-test machine # [ 78.984560] systemd[1]: Stopped target Remote File Systems. machine # [ 78.986985] systemd[1]: Stopped target Local File Systems. machine # [ 79.471995] systemd[1]: Reload requested from client PID 3202 ('.switch-to-conf') (unit backdoor.service)... machine # [ 79.474000] systemd[1]: Reloading... machine # [ 80.037994] systemd[1]: Reloading finished in 561 ms. machine # [ 80.192501] systemd[583]: Reexecution requested from client PID 3271 ('switch-to-confi')... machine # [ 80.195875] systemd[583]: Reexecuting. machine # [ 80.253773] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 80.446678] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 80.453946] systemd[583]: Reload requested from client PID 3271 ('switch-to-confi')... machine # [ 80.456386] systemd[583]: Reloading... machine # [ 80.640106] systemd[583]: Reloading finished in 182 ms. machine # [ 80.657639] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 80.666619] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 80.668871] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 80.671697] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 80.727308] systemd[583]: Reload requested from client PID 3283 ('systemctl') (unit nixos-activation.service)... machine # [ 80.731712] systemd[583]: Reloading... machine # [ 80.920885] systemd[583]: Reloading finished in 190 ms. machine # [ 80.936162] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 80.955092] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 80.958248] systemd[583]: Reload requested from client PID 3271 ('switch-to-confi')... machine # [ 80.961741] systemd[583]: Reloading... machine # [ 81.147119] systemd[583]: Reloading finished in 186 ms. machine # [ 81.160180] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 81.170649] systemd[583]: Stopped migrated.service. machine # [ 81.172831] systemd[583]: Stopping migrated.service... machine # [ 81.175499] systemd[583]: Starting migrated.service... machine # [ 81.213775] systemd[583]: Finished migrated.service. machine # [ 81.220795] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 81.222709] systemd[1]: Stopping Reactivate sysinit units... machine # [ 81.224522] systemd[1]: Reached target Reactivate sysinit units. machine # [ 81.227022] systemd[1]: Reached target Local File Systems. machine # [ 81.230995] systemd[1]: Reached target Remote File Systems. machine # [ 81.236584] systemd[1]: Starting linger-users.service... machine # [ 81.239550] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 81.250958] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 81.251837] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 81.254600] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 81.264572] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 81.266154] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 81.269717] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 81.271730] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 81.273521] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 81.331035] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 81.334092] systemd[1]: Finished linger-users.service. machine # [ 81.591746] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 81.595066] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 81.851713] nixos[3202]: finished switching to system configuration /nix/store/13l69miwn0hkcfqwzj9kj9c27zww979i-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 3.39 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 81.937350] sudo[3352]: 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 # [ 81.955693] sudo[3352]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 81.974017] sudo[3352]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user 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 # [ 82.028994] sudo[3358]: 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 # [ 82.042783] sudo[3358]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 82.072564] sudo[3358]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.10 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 82.123186] sudo[3364]: 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 # [ 82.137149] sudo[3364]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 82.149445] sudo[3364]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 82.655708] nixos[3373]: switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test machine # [ 82.659873] systemd[1]: Stopped target Remote File Systems. machine # [ 82.661107] systemd[1]: Stopped target Local File Systems. machine # [ 83.090151] systemd[1]: Reload requested from client PID 3373 ('.switch-to-conf') (unit backdoor.service)... machine # [ 83.092221] systemd[1]: Reloading... machine # [ 83.665541] systemd[1]: Reloading finished in 570 ms. machine # [ 83.809002] systemd[583]: Stopped migrated.service. machine # [ 83.812537] systemd[583]: Reexecution requested from client PID 3442 ('switch-to-confi')... machine # [ 83.813878] systemd[583]: Reexecuting. machine # [ 83.877694] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 84.068951] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 84.075349] systemd[583]: Reload requested from client PID 3442 ('switch-to-confi')... machine # [ 84.078163] systemd[583]: Reloading... machine # [ 84.263240] systemd[583]: Reloading finished in 184 ms. machine # [ 84.280176] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 84.290397] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 84.291470] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 84.294456] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 84.341128] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 84.345163] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 84.348127] systemd[1]: Stopping Reactivate sysinit units... machine # [ 84.348971] systemd[1]: Reached target Reactivate sysinit units. machine # [ 84.350276] systemd[1]: Reached target Local File Systems. machine # [ 84.354683] systemd[1]: Reached target Remote File Systems. machine # [ 84.360278] systemd[1]: Starting linger-users.service... machine # [ 84.370087] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 84.375107] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 84.378123] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 84.384193] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 84.386312] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 84.387815] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 84.392609] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 84.394502] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 84.396994] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 84.453708] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 84.455490] systemd[1]: Finished linger-users.service. machine # [ 84.707455] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 84.710563] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 84.968652] nixos[3373]: finished switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.83 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 85.053166] sudo[3510]: 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 # [ 85.071511] sudo[3510]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 85.087230] sudo[3510]: 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 # [ 85.134198] sudo[3516]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 85.147947] sudo[3516]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 85.161327] sudo[3516]: 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.07 seconds) machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 85.205956] sudo[3522]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 85.218875] sudo[3522]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 85.230917] sudo[3522]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 85.274085] sudo[3528]: 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 # [ 85.286822] sudo[3528]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 85.298426] sudo[3528]: 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.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 85.341115] sudo[3534]: 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 # [ 85.353473] sudo[3534]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 85.364700] systemd[583]: Reload requested from client PID 3539 ('systemctl')... machine # [ 85.368641] systemd[583]: Reloading... machine # [ 85.553021] systemd[583]: Reloading finished in 185 ms. machine # [ 85.568179] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 85.581184] sudo[3534]: 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.28 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 85.627157] sudo[3544]: 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 # [ 85.640879] sudo[3544]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 85.656904] systemd[583]: Starting migrated.service... machine # [ 85.693479] systemd[583]: Finished migrated.service. machine # [ 85.698338] sudo[3544]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.12 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 85.742344] sudo[3552]: 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 # [ 85.755051] sudo[3552]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 85.767583] sudo[3552]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 85.811543] sudo[3558]: 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 # [ 85.824785] sudo[3558]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 85.835898] sudo[3558]: 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.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 85.879362] sudo[3564]: 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 # [ 85.892853] sudo[3564]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 85.913846] sudo[3564]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.08 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1 machine # [ 86.414167] nixos[3573]: switching to system configuration /nix/store/lpbbdz3hihjq7b6jc616lpy0vq1wf6p1-nixos-system-machine-test machine # [ 86.416986] systemd[1]: Stopped target Remote File Systems. machine # [ 86.419247] systemd[1]: Stopped target Local File Systems. machine # [ 86.932255] systemd[1]: Reload requested from client PID 3573 ('.switch-to-conf') (unit backdoor.service)... machine # [ 86.934252] systemd[1]: Reloading... machine # [ 87.527905] systemd[1]: Reloading finished in 591 ms. machine # [ 87.688432] systemd[583]: Reexecution requested from client PID 3642 ('switch-to-confi')... machine # [ 87.691205] systemd[583]: Reexecuting. machine # [ 87.749741] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 87.945221] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 87.952096] systemd[583]: Reload requested from client PID 3642 ('switch-to-confi')... machine # [ 87.953385] systemd[583]: Reloading... machine # [ 88.141254] systemd[583]: Reloading finished in 185 ms. machine # [ 88.152178] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 88.162260] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 88.163247] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 88.166055] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 88.223897] systemd[583]: Reload requested from client PID 3654 ('systemctl') (unit nixos-activation.service)... machine # [ 88.228109] systemd[583]: Reloading... machine # [ 88.422721] systemd[583]: Reloading finished in 195 ms. machine # [ 88.436170] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 88.455303] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 88.458508] systemd[583]: Reload requested from client PID 3642 ('switch-to-confi')... machine # [ 88.459739] systemd[583]: Reloading... machine # [ 88.650981] systemd[583]: Reloading finished in 189 ms. machine # [ 88.664168] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 88.677028] systemd[583]: Reloading migrated.service... machine # [ 88.710978] systemd[583]: Reloaded migrated.service. machine # [ 88.717733] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 88.720718] systemd[1]: Stopping Reactivate sysinit units... machine # [ 88.721614] systemd[1]: Reached target Reactivate sysinit units. machine # [ 88.727277] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 88.729690] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 88.733366] systemd[1]: Reached target Remote File Systems. machine # [ 88.738328] systemd[1]: Starting linger-users.service... machine # [ 88.743203] systemd[1]: Reached target Local File Systems. machine # [ 88.752643] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 88.754144] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 88.754907] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 88.763013] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 88.765373] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 88.766920] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 88.768217] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 88.830956] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 88.834012] systemd[1]: Finished linger-users.service. machine # [ 89.096696] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 89.099871] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 89.356851] nixos[3573]: finished switching to system configuration /nix/store/lpbbdz3hihjq7b6jc616lpy0vq1wf6p1-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 3.45 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 89.443595] sudo[3723]: 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 # [ 89.463897] sudo[3723]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 89.481180] sudo[3723]: 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 # [ 89.533388] sudo[3729]: 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 # [ 89.547287] sudo[3729]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 89.559543] sudo[3729]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 90.072615] nixos[3738]: switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test machine # [ 90.075298] systemd[1]: Stopped target Remote File Systems. machine # [ 90.077436] systemd[1]: Stopped target Local File Systems. machine # [ 90.509905] systemd[1]: Reload requested from client PID 3738 ('.switch-to-conf') (unit backdoor.service)... machine # [ 90.511865] systemd[1]: Reloading... machine # [ 91.098602] systemd[1]: Reloading finished in 584 ms. machine # [ 91.241273] systemd[583]: Stopped migrated.service. machine # [ 91.244513] systemd[583]: Reexecution requested from client PID 3807 ('switch-to-confi')... machine # [ 91.247264] systemd[583]: Reexecuting. machine # [ 91.301536] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 91.489895] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 91.497080] systemd[583]: Reload requested from client PID 3807 ('switch-to-confi')... machine # [ 91.499455] systemd[583]: Reloading... machine # [ 91.687490] systemd[583]: Reloading finished in 186 ms. machine # [ 91.700147] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 91.710851] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 91.713184] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 91.716073] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 91.760609] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 91.765097] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 91.767442] systemd[1]: Stopping Reactivate sysinit units... machine # [ 91.769067] systemd[1]: Reached target Reactivate sysinit units. machine # [ 91.772685] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 91.775100] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 91.778356] systemd[1]: Reached target Remote File Systems. machine # [ 91.779650] systemd[1]: Reached target Local File Systems. machine # [ 91.784705] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 91.789956] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 91.790872] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 91.792546] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 91.800715] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 91.802379] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 91.803631] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 92.110748] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 92.115565] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 92.117858] systemd[1]: Starting linger-users.service... machine # [ 92.177960] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 92.180820] systemd[1]: Finished linger-users.service. machine # [ 92.438536] nixos[3738]: finished switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.89 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 92.522176] sudo[3875]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 92.541107] sudo[3875]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 92.555896] sudo[3875]: 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 # [ 92.603501] sudo[3881]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 92.617049] sudo[3881]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 92.627145] sudo[3881]: 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.07 seconds) machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 92.667594] sudo[3887]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 92.680537] sudo[3887]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 92.692605] sudo[3887]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 92.738321] sudo[3893]: 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 # [ 92.751899] sudo[3893]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 92.764849] sudo[3893]: 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.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 92.812736] sudo[3899]: 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 # [ 92.825514] sudo[3899]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 92.835566] systemd[583]: Reload requested from client PID 3904 ('systemctl')... machine # [ 92.839562] systemd[583]: Reloading... machine # [ 93.027273] systemd[583]: Reloading finished in 188 ms. machine # [ 93.040144] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 93.052523] sudo[3899]: 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.29 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 93.097779] sudo[3909]: 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 # [ 93.112763] sudo[3909]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 93.128702] systemd[583]: Starting migrated.service... machine # [ 93.161517] systemd[583]: Finished migrated.service. machine # [ 93.166067] sudo[3909]: 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.11 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 93.210942] sudo[3917]: 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 # [ 93.223883] sudo[3917]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 93.235895] sudo[3917]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 93.280066] sudo[3923]: 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 # [ 93.292759] sudo[3923]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 93.304472] sudo[3923]: 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.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 93.350393] sudo[3929]: 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 # [ 93.363025] sudo[3929]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 93.384103] sudo[3929]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.08 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1 machine # [ 93.876647] nixos[3938]: switching to system configuration /nix/store/2y8zkwdzgvvqg2qvlagzsnarhxzibnaq-nixos-system-machine-test machine # [ 93.880595] systemd[1]: Stopped target Local File Systems. machine # [ 93.881807] systemd[1]: Stopped target Remote File Systems. machine # [ 94.345368] systemd[1]: Reload requested from client PID 3938 ('.switch-to-conf') (unit backdoor.service)... machine # [ 94.347339] systemd[1]: Reloading... machine # [ 94.921304] systemd[1]: Reloading finished in 570 ms. machine # [ 95.080494] systemd[583]: Reexecution requested from client PID 4007 ('switch-to-confi')... machine # [ 95.081971] systemd[583]: Reexecuting. machine # [ 95.146095] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 95.345004] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 95.352763] systemd[583]: Reload requested from client PID 4007 ('switch-to-confi')... machine # [ 95.357255] systemd[583]: Reloading... machine # [ 95.539742] systemd[583]: Reloading finished in 183 ms. machine # [ 95.552144] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 95.563241] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 95.564354] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 95.567207] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 95.621236] systemd[583]: Reload requested from client PID 4019 ('systemctl') (unit nixos-activation.service)... machine # [ 95.623746] systemd[583]: Reloading... machine # [ 95.812880] systemd[583]: Reloading finished in 187 ms. machine # [ 95.828158] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 95.847738] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 95.850526] systemd[583]: Reload requested from client PID 4007 ('switch-to-confi')... machine # [ 95.852787] systemd[583]: Reloading... machine # [ 96.040069] systemd[583]: Reloading finished in 186 ms. machine # [ 96.052154] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 96.066878] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 96.069932] systemd[1]: Stopping Reactivate sysinit units... machine # [ 96.072069] systemd[1]: Reached target Reactivate sysinit units. machine # [ 96.072951] systemd[1]: Reached target Remote File Systems. machine # [ 96.074652] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 96.075926] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 96.079872] systemd[1]: Reached target Local File Systems. machine # [ 96.087583] systemd[1]: Starting linger-users.service... machine # [ 96.093933] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 96.098957] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 96.103508] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 96.105219] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 96.107765] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 96.113148] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 96.114479] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 96.174917] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 96.178207] systemd[1]: Finished linger-users.service. machine # [ 96.434959] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 96.438200] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 96.695135] nixos[3938]: finished switching to system configuration /nix/store/2y8zkwdzgvvqg2qvlagzsnarhxzibnaq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 3.32 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 96.775841] sudo[4086]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 96.796879] sudo[4086]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 96.813406] sudo[4086]: 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 # [ 96.865024] sudo[4092]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 96.878431] sudo[4092]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 96.890061] sudo[4092]: 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.07 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 97.413919] nixos[4101]: switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test machine # [ 97.416759] systemd[1]: Stopped target Remote File Systems. machine # [ 97.419028] systemd[1]: Stopped target Local File Systems. machine # [ 97.853934] systemd[1]: Reload requested from client PID 4101 ('.switch-to-conf') (unit backdoor.service)... machine # [ 97.855929] systemd[1]: Reloading... machine # [ 98.427925] systemd[1]: Reloading finished in 569 ms. machine # [ 98.570338] systemd[583]: Stopped migrated.service. machine # [ 98.573708] systemd[583]: Reexecution requested from client PID 4170 ('switch-to-confi')... machine # [ 98.575006] systemd[583]: Reexecuting. machine # [ 98.641746] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 98.829212] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 98.835821] systemd[583]: Reload requested from client PID 4170 ('switch-to-confi')... machine # [ 98.837970] systemd[583]: Reloading... machine # [ 99.017389] systemd[583]: Reloading finished in 178 ms. machine # [ 99.032161] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 99.041393] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 99.043585] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 99.046243] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 99.091182] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 99.095194] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 99.096482] systemd[1]: Stopping Reactivate sysinit units... machine # [ 99.097280] systemd[1]: Reached target Reactivate sysinit units. machine # [ 99.102552] systemd[1]: Reached target Remote File Systems. machine # [ 99.108097] systemd[1]: Starting linger-users.service... machine # [ 99.110747] systemd[1]: Reached target Local File Systems. machine # [ 99.113108] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 99.114361] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 99.120396] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 99.126928] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 99.127818] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 99.132371] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 99.137901] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 99.139496] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 99.142405] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 99.199640] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 99.203059] systemd[1]: Finished linger-users.service. machine # [ 99.454720] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 99.458019] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 99.714720] nixos[4101]: finished switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.84 seconds) machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 99.796878] sudo[4238]: 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 # [ 99.813953] sudo[4238]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 99.829723] sudo[4238]: 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 # [ 99.877882] sudo[4244]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user machine # [ 99.891239] sudo[4244]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 99.903064] sudo[4244]: 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.07 seconds) machine: must succeed: sudo -u usertest cp /nix/store/sjwrvqsv40pz3pq93zpd2s2k0jw0h8qf-migrated.service ~usertest/.local/share/systemd/user/migrated.service machine # [ 99.945948] sudo[4250]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/sjwrvqsv40pz3pq93zpd2s2k0jw0h8qf-migrated.service /home/usertest/.local/share/systemd/user/migrated.service machine # [ 99.958682] sudo[4250]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 99.970951] sudo[4250]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/sjwrvqsv40pz3pq93zpd2s2k0jw0h8qf-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 100.013093] sudo[4256]: 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 # [ 100.025495] sudo[4256]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 100.035266] systemd[583]: Reload requested from client PID 4261 ('systemctl')... machine # [ 100.037530] systemd[583]: Reloading... machine # [ 100.219003] systemd[583]: Reloading finished in 179 ms. machine # [ 100.232207] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 100.245305] sudo[4256]: 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.28 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 100.290911] sudo[4266]: 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 # [ 100.303527] sudo[4266]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 100.318588] systemd[583]: Starting migrated.service... machine # [ 100.356981] systemd[583]: Finished migrated.service. machine # [ 100.361514] sudo[4266]: 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.11 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 100.404190] sudo[4274]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service machine # [ 100.416892] sudo[4274]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 100.429113] sudo[4274]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 100.472832] sudo[4280]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner machine # [ 100.485783] sudo[4280]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 100.497339] sudo[4280]: 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.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 100.539993] sudo[4286]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service machine # [ 100.552641] sudo[4286]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 100.596230] sudo[4286]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.10 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 101.112256] nixos[4295]: switching to system configuration /nix/store/2q6v66pn7bsy861rkivfp4x66plzgmb9-nixos-system-machine-test machine # [ 101.114973] systemd[1]: Stopped target Local File Systems. machine # [ 101.116903] systemd[1]: Stopped target Remote File Systems. machine # [ 101.598051] systemd[1]: Reload requested from client PID 4295 ('.switch-to-conf') (unit backdoor.service)... machine # [ 101.600209] systemd[1]: Reloading... machine # [ 102.173613] systemd[1]: Reloading finished in 571 ms. machine # [ 102.325091] systemd[583]: Reexecution requested from client PID 4363 ('switch-to-confi')... machine # [ 102.329725] systemd[583]: Reexecuting. machine # [ 102.373206] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 102.567763] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 102.574387] systemd[583]: Reload requested from client PID 4363 ('switch-to-confi')... machine # [ 102.576917] systemd[583]: Reloading... machine # [ 102.764131] systemd[583]: Reloading finished in 186 ms. machine # [ 102.776150] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 102.786096] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 102.787095] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 102.789832] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 102.835713] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 102.837829] systemd[583]: Reload requested from client PID 4363 ('switch-to-confi')... machine # [ 102.840162] systemd[583]: Reloading... machine # [ 103.027113] systemd[583]: Reloading finished in 185 ms. machine # [ 103.040156] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 103.050639] systemd[583]: Stopped migrated.service. machine # [ 103.052728] systemd[583]: Stopping migrated.service... machine # [ 103.055371] systemd[583]: Starting migrated.service... machine # [ 103.093594] systemd[583]: Finished migrated.service. machine # [ 103.099917] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 103.102067] systemd[1]: Stopping Reactivate sysinit units... machine # [ 103.102922] systemd[1]: Reached target Reactivate sysinit units. machine # [ 103.108226] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 103.112317] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 103.114163] systemd[1]: Reached target Remote File Systems. machine # [ 103.115005] systemd[1]: Reached target Local File Systems. machine # [ 103.118417] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 103.125872] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 103.126835] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 103.129166] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 103.133603] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 103.136122] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 103.140263] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 103.429099] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 103.431429] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 103.436664] systemd[1]: Starting linger-users.service... machine # [ 103.497290] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 103.500130] systemd[1]: Finished linger-users.service. machine # [ 103.758347] nixos[4295]: finished switching to system configuration /nix/store/2q6v66pn7bsy861rkivfp4x66plzgmb9-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 3.17 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 103.842488] sudo[4438]: 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 # [ 103.859797] sudo[4438]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 103.873873] sudo[4438]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user 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 # [ 103.922470] sudo[4444]: 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 # [ 103.936706] sudo[4444]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 103.963949] sudo[4444]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.09 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 104.007290] sudo[4450]: 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 # [ 104.019802] sudo[4450]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 104.031372] sudo[4450]: 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.07 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 104.528679] nixos[4459]: switching to system configuration /nix/store/2q6v66pn7bsy861rkivfp4x66plzgmb9-nixos-system-machine-test machine # [ 104.531440] systemd[1]: Stopped target Local File Systems. machine # [ 104.533862] systemd[1]: Stopped target Remote File Systems. machine # [ 104.957987] systemd[1]: Reload requested from client PID 4459 ('.switch-to-conf') (unit backdoor.service)... machine # [ 104.959930] systemd[1]: Reloading... machine # [ 105.529308] systemd[1]: Reloading finished in 566 ms. machine # [ 105.682992] systemd[583]: Reexecution requested from client PID 4528 ('switch-to-confi')... machine # [ 105.685176] systemd[583]: Reexecuting. machine # [ 105.749943] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 105.942537] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 105.950054] systemd[583]: Reload requested from client PID 4528 ('switch-to-confi')... machine # [ 105.951361] systemd[583]: Reloading... machine # [ 106.138962] systemd[583]: Reloading finished in 186 ms. machine # [ 106.152170] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 106.162207] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 106.164617] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 106.168363] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 106.212075] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 106.216455] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 106.219332] systemd[1]: Stopping Reactivate sysinit units... machine # [ 106.222567] systemd[1]: Reached target Reactivate sysinit units. machine # [ 106.226521] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 106.228135] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 106.231008] systemd[1]: Reached target Remote File Systems. machine # [ 106.231851] systemd[1]: Starting linger-users.service... machine # [ 106.236984] systemd[1]: Reached target Local File Systems. machine # [ 106.240278] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 106.246184] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 106.249894] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 106.257423] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 106.259645] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 106.263919] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 106.267021] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 106.327772] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 106.331338] systemd[1]: Finished linger-users.service. machine # [ 106.590128] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 106.593408] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 106.851352] nixos[4459]: finished switching to system configuration /nix/store/2q6v66pn7bsy861rkivfp4x66plzgmb9-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 2.83 seconds) machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd machine # [ 106.934394] sudo[4596]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd machine # [ 106.951768] sudo[4596]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 106.966362] sudo[4596]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.11 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 107.016674] sudo[4602]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload machine # [ 107.030009] sudo[4602]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 107.040161] systemd[583]: Reload requested from client PID 4607 ('systemctl')... machine # [ 107.042837] systemd[583]: Reloading... machine # [ 107.234887] systemd[583]: Reloading finished in 191 ms. machine # [ 107.248152] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 107.259528] sudo[4602]: 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.29 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 107.773575] nixos[4615]: switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test machine # [ 107.776251] systemd[1]: Stopped target Remote File Systems. machine # [ 107.778463] systemd[1]: Stopped target Local File Systems. machine # [ 108.211588] systemd[1]: Reload requested from client PID 4615 ('.switch-to-conf') (unit backdoor.service)... machine # [ 108.213791] systemd[1]: Reloading... machine # [ 108.781736] systemd[1]: Reloading finished in 565 ms. machine # [ 108.921555] systemd[583]: Stopped migrated.service. machine # [ 108.924497] systemd[583]: Reexecution requested from client PID 4683 ('switch-to-confi')... machine # [ 108.927481] systemd[583]: Reexecuting. machine # [ 108.981774] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 109.165495] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 109.172686] systemd[583]: Reload requested from client PID 4683 ('switch-to-confi')... machine # [ 109.175132] systemd[583]: Reloading... machine # [ 109.359523] systemd[583]: Reloading finished in 183 ms. machine # [ 109.376180] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 109.386505] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 109.389090] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 109.392090] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 109.437926] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 109.442140] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 109.443351] systemd[1]: Stopping Reactivate sysinit units... machine # [ 109.445971] systemd[1]: Reached target Reactivate sysinit units. machine # [ 109.448274] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 109.449565] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 109.453162] systemd[1]: Reached target Local File Systems. machine # [ 109.456296] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 109.460578] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 109.461455] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 109.462621] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 109.466217] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 109.468620] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 109.478010] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 109.481747] systemd[1]: Reached target Remote File Systems. machine # [ 109.782990] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 109.785173] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 109.790105] systemd[1]: Starting linger-users.service... machine # [ 109.850166] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 109.853008] systemd[1]: Finished linger-users.service. machine # [ 110.110967] nixos[4615]: finished switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.86 seconds) machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants machine # [ 110.191480] sudo[4752]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants machine # [ 110.208655] sudo[4752]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 110.222104] sudo[4752]: 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.10 seconds) machine: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service machine # [ 110.270919] sudo[4758]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service /home/usertest/.config/systemd/user/migrated.service machine # [ 110.285605] sudo[4758]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 110.298467] sudo[4758]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest cp /nix/store/033zajsa9ih6jnlg346n0kjbndcbkl75-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.08 seconds) machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service machine # [ 110.343048] sudo[4764]: 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 # [ 110.356358] sudo[4764]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 110.368232] sudo[4764]: 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.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 110.411099] sudo[4770]: 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 # [ 110.423720] sudo[4770]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 110.433439] systemd[583]: Reload requested from client PID 4775 ('systemctl')... machine # [ 110.435999] systemd[583]: Reloading... machine # [ 110.625236] systemd[583]: Reloading finished in 188 ms. machine # [ 110.640147] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 110.653985] sudo[4770]: 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.29 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service machine # [ 110.698774] sudo[4780]: 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 # [ 110.711272] sudo[4780]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 110.727083] systemd[583]: Starting migrated.service... machine # [ 110.760524] systemd[583]: Finished migrated.service. machine # [ 110.764713] sudo[4780]: 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.11 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service machine # [ 110.808578] sudo[4788]: 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 # [ 110.822012] sudo[4788]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 110.835045] sudo[4788]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner machine # [ 110.883093] sudo[4794]: 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 # [ 110.895889] sudo[4794]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 110.908124] sudo[4794]: 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.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 110.952768] sudo[4800]: 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 # [ 110.965900] sudo[4800]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 110.987702] sudo[4800]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.08 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1 machine # [ 111.500540] nixos[4809]: switching to system configuration /nix/store/2q6v66pn7bsy861rkivfp4x66plzgmb9-nixos-system-machine-test machine # [ 111.503279] systemd[1]: Stopped target Local File Systems. machine # [ 111.505510] systemd[1]: Stopped target Remote File Systems. machine # [ 111.931863] systemd[1]: Reload requested from client PID 4809 ('.switch-to-conf') (unit backdoor.service)... machine # [ 111.934114] systemd[1]: Reloading... machine # [ 112.501201] systemd[1]: Reloading finished in 565 ms. machine # [ 112.633143] systemd[583]: Reexecution requested from client PID 4877 ('switch-to-confi')... machine # [ 112.636683] systemd[583]: Reexecuting. machine # [ 112.681094] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 112.865035] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 112.871671] systemd[583]: Reload requested from client PID 4877 ('switch-to-confi')... machine # [ 112.875073] systemd[583]: Reloading... machine # [ 113.055793] systemd[583]: Reloading finished in 181 ms. machine # [ 113.068140] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 113.077605] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 113.078572] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 113.081225] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 113.128248] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 113.130210] systemd[583]: Reload requested from client PID 4877 ('switch-to-confi')... machine # [ 113.132886] systemd[583]: Reloading... machine # [ 113.315340] systemd[583]: Reloading finished in 182 ms. machine # [ 113.328148] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 113.342064] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 113.344845] systemd[1]: Stopping Reactivate sysinit units... machine # [ 113.346245] systemd[1]: Reached target Reactivate sysinit units. machine # [ 113.347990] systemd[1]: Reached target Remote File Systems. machine # [ 113.351663] systemd[1]: Reached target Local File Systems. machine # [ 113.354299] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 113.360932] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 113.361818] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 113.362997] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 113.365935] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 113.376456] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 113.377794] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 113.378948] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 113.380331] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 113.677679] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 113.680682] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 113.684684] systemd[1]: Starting linger-users.service... machine # [ 113.741257] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 113.743682] systemd[1]: Finished linger-users.service. machine # [ 114.003016] nixos[4809]: finished switching to system configuration /nix/store/2q6v66pn7bsy861rkivfp4x66plzgmb9-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 3.02 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service machine # [ 114.081251] sudo[4950]: 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 # [ 114.098781] sudo[4950]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 114.123891] sudo[4950]: 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 # [ 114.169053] sudo[4956]: 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 # [ 114.182363] sudo[4956]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 114.193834] sudo[4956]: 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.07 seconds) machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd machine # [ 114.235209] sudo[4962]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd machine # [ 114.247646] sudo[4962]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 114.259146] sudo[4962]: pam_unix(sudo:session): session closed for user usertest machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.07 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload machine # [ 114.300761] sudo[4968]: 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 # [ 114.312993] sudo[4968]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 114.322496] systemd[583]: Reload requested from client PID 4973 ('systemctl')... machine # [ 114.324998] systemd[583]: Reloading... machine # [ 114.508512] systemd[583]: Reloading finished in 181 ms. machine # [ 114.524199] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 114.537711] sudo[4968]: 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.28 seconds) machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service machine # [ 114.581505] sudo[4978]: 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 # [ 114.594021] sudo[4978]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0) machine # [ 114.607218] systemd[583]: Stopped migrated.service. machine # [ 114.611205] sudo[4978]: 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.07 seconds) machine: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1 machine # [ 115.110466] nixos[4987]: switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test machine # [ 115.113179] systemd[1]: Stopped target Local File Systems. machine # [ 115.115285] systemd[1]: Stopped target Remote File Systems. machine # [ 115.539734] systemd[1]: Reload requested from client PID 4987 ('.switch-to-conf') (unit backdoor.service)... machine # [ 115.541991] systemd[1]: Reloading... machine # [ 116.109275] systemd[1]: Reloading finished in 565 ms. machine # [ 116.242507] systemd[583]: Reexecution requested from client PID 5056 ('switch-to-confi')... machine # [ 116.246184] systemd[583]: Reexecuting. machine # [ 116.285371] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 116.467027] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 116.473921] systemd[583]: Reload requested from client PID 5056 ('switch-to-confi')... machine # [ 116.476097] systemd[583]: Reloading... machine # [ 116.655017] systemd[583]: Reloading finished in 177 ms. machine # [ 116.672159] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy machine # [ 116.682592] systemd[583]: Stopped Run user-specific NixOS activation. machine # [ 116.684864] systemd[583]: Stopping Run user-specific NixOS activation... machine # [ 116.687634] systemd[583]: Starting Run user-specific NixOS activation... machine # [ 116.730532] systemd[583]: Finished Run user-specific NixOS activation. machine # [ 116.734861] systemd[1]: Stopped target Reactivate sysinit units. machine # [ 116.736683] systemd[1]: Stopping Reactivate sysinit units... machine # [ 116.737899] systemd[1]: Reached target Reactivate sysinit units. machine # [ 116.740477] systemd[1]: Reached target Remote File Systems. machine # [ 116.744802] systemd[1]: Starting linger-users.service... machine # [ 116.754044] systemd[1]: Reached target Local File Systems. machine # [ 116.754862] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 116.760177] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 116.761049] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 116.762228] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 116.773737] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 116.778124] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 116.780218] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 116.781563] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 116.783275] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 116.839925] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 116.841148] systemd[1]: Finished linger-users.service. machine # [ 117.094414] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 117.097531] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 117.353985] nixos[4987]: finished switching to system configuration /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test machine: (finished: must succeed: /nix/store/nrkndmhgp9vqz1yna8sbq7ihxapwagcq-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.75 seconds) (finished: subtest: user services, in 83.04 seconds) (finished: run the VM test script, in 118.29 seconds) test script finished in 118.36s cleanup kill QemuMachine (pid 45) machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14) (finished: cleanup, in 0.17 seconds) additionally exposed symbols: machine, other, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh