vm-test-run-switch-test-user
checks.aarch64-linux.switchTest-user
· build #46
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7machine: waiting for unit multi-user.target8machine: waiting for the VM to finish booting9machine: starting vm10machine # Disk image does not exist, creating the virtualisation disk image...11machine # Formatting '/build/vm-state-machine/tmp.QK7KlHQkAd', fmt=raw size=107374182412machine: QEMU running (pid 45)13machine # mke2fs 1.47.4 (6-Mar-2025)14machine # Discarding device blocks: 0/262144 done15machine # Creating filesystem with 262144 4k blocks and 65536 inodes16machine # Filesystem UUID: de6f5adc-a7c2-4a41-976a-d69787b518d517machine # Superblock backups stored on blocks:18machine # 32768, 98304, 163840, 22937619machine # 20machine # Allocating group tables: 0/8 done21machine # Writing inode tables: 0/8 done22machine # Creating journal (8192 blocks): done23machine # Writing superblocks and filesystem accounting information: 0/8 done24machine # 25machine # Virtualisation disk image created.26machine # Starting virtiofs daemons...27machine # [2026-09-27T03:13:14Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28machine # [2026-09-27T03:13:14Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29machine # [2026-09-27T03:13:14Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)30machine # [2026-09-27T03:13:14Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)31machine # [2026-09-27T03:13:14Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether32machine # [2026-09-27T03:13:14Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether33machine # [2026-09-27T03:13:14Z INFO virtiofsd] Waiting for vhost-user socket connection...34machine # [2026-09-27T03:13:14Z INFO virtiofsd] Waiting for vhost-user socket connection...35machine # [2026-09-27T03:13:14Z INFO virtiofsd] Waiting for vhost-user socket connection...36machine # [2026-09-27T03:13:14Z INFO virtiofsd] Client connected, servicing requests37machine # [2026-09-27T03:13:14Z INFO virtiofsd] Client connected, servicing requests38machine # [2026-09-27T03:13:14Z INFO virtiofsd] Client connected, servicing requests39machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]40machine # [ 0.000000] Linux version 6.18.53 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 21 13:06:06 UTC 202641machine # [ 0.000000] KASLR enabled42machine # [ 0.000000] random: crng init done43machine # [ 0.000000] Machine model: linux,dummy-virt44machine # [ 0.000000] efi: UEFI not found.45machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT46machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]47machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fdee700-0x7fdf1e7f]48machine # [ 0.000000] Zone ranges:49machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]50machine # [ 0.000000] DMA32 empty51machine # [ 0.000000] Normal empty52machine # [ 0.000000] Device empty53machine # [ 0.000000] Movable zone start for each node54machine # [ 0.000000] Early memory node ranges55machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]56machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]57machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000058machine # [ 0.000000] psci: probing for conduit method from DT.59machine # [ 0.000000] psci: PSCIv1.3 detected in firmware.60machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs61machine # [ 0.000000] psci: Trusted OS migration not required62machine # [ 0.000000] psci: SMC Calling Convention v1.163machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)64machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u31129665machine # [ 0.000000] Detected PIPT I-cache on CPU066machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)67machine # [ 0.000000] CPU features: detected: GICv3 CPU interface68machine # [ 0.000000] CPU features: detected: Spectre-v469machine # [ 0.000000] CPU features: detected: Spectre-BHB70machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_3871machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_2372machine # [ 0.000000] alternatives: applying boot alternatives73machine # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/init regInfo=/nix/.ro-store/csmdccsr9xahibh6dac2xmi6f93av4jh-closure-info/registration console=ttyAMA0,115200n8 console=tty074machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/csmdccsr9xahibh6dac2xmi6f93av4jh-closure-info/registration", will be passed to user space.75machine # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes76machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)77machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)78machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB79machine # [ 0.000000] software IO TLB: area num 1.80machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)81machine # [ 0.000000] Fallback order for Node 0: 082machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 26214483machine # [ 0.000000] Policy zone: DMA84machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off85machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=186machine # [ 0.000000] allocated 2097152 bytes of page_ext87machine # [ 0.000000] ftrace: allocating 74963 entries in 294 pages88machine # [ 0.000000] ftrace: allocated 294 pages with 4 groups89machine # [ 0.000000] rcu: Hierarchical RCU implementation.90machine # [ 0.000000] rcu: RCU event tracing is enabled.91machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.92machine # [ 0.000000] Trampoline variant of Tasks RCU enabled.93machine # [ 0.000000] Rude variant of Tasks RCU enabled.94machine # [ 0.000000] Tracing variant of Tasks RCU enabled.95machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.96machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=197machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.98machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.99machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.100machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0101machine # [ 0.000000] GICv3: 256 SPIs implemented102machine # [ 0.000000] GICv3: 0 Extended SPIs implemented103machine # [ 0.000000] Root IRQ handler: gic_handle_irq104machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI105machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0106machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000107machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]108machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)109machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)110machine # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000111machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000112machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.113machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns114machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).115machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns116machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns117machine # [ 0.000033] arm-pv: using stolen time PV118machine # [ 0.000444] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)119machine # [ 0.000611] Console: colour dummy device 80x25120machine # [ 0.000619] printk: legacy console [tty0] enabled121machine # [ 0.000841] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)122machine # [ 0.000848] pid_max: default: 32768 minimum: 301123machine # [ 0.000926] LSM: initializing lsm=capability,landlock,yama,bpf,ima124machine # [ 0.001063] landlock: Up and running.125machine # [ 0.001066] Yama: becoming mindful.126machine # [ 0.001582] LSM support for eBPF active127machine # [ 0.001700] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)128machine # [ 0.001720] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)129machine # [ 0.003772] rcu: Hierarchical SRCU implementation.130machine # [ 0.003778] rcu: Max phase no-delay instances is 1000.131machine # [ 0.005089] fsl-mc MSI: its@8080000 domain created132machine # [ 0.005211] EFI services will not be available.133machine # [ 0.005313] smp: Bringing up secondary CPUs ...134machine # [ 0.005321] smp: Brought up 1 node, 1 CPU135machine # [ 0.005324] SMP: Total of 1 processors activated.136machine # [ 0.005327] CPU: All CPU(s) started at EL1137machine # [ 0.005340] CPU features: detected: Branch Target Identification138machine # [ 0.005345] CPU features: detected: ARMv8.4 Translation Table Level139machine # [ 0.005348] CPU features: detected: Instruction cache invalidation not required for I/D coherence140machine # [ 0.005352] CPU features: detected: Data cache clean to the PoU not required for I/D coherence141machine # [ 0.005356] CPU features: detected: Common not Private translations142machine # [ 0.005359] CPU features: detected: CRC32 instructions143machine # [ 0.005362] CPU features: detected: Data cache clean to Point of Deep Persistence144machine # [ 0.005365] CPU features: detected: Data cache clean to Point of Persistence145machine # [ 0.005368] CPU features: detected: Data independent timing control (DIT)146machine # [ 0.005371] CPU features: detected: E0PD147machine # [ 0.005373] CPU features: detected: Enhanced Counter Virtualization148machine # [ 0.005376] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)149machine # [ 0.005380] CPU features: detected: Enhanced Virtualization Traps150machine # [ 0.005383] CPU features: detected: Fine Grained Traps151machine # [ 0.005387] CPU features: detected: Generic authentication (architected QARMA5 algorithm)152machine # [ 0.005391] CPU features: detected: RCpc load-acquire (LDAPR)153machine # [ 0.005394] CPU features: detected: LSE atomic instructions154machine # [ 0.005397] CPU features: detected: Privileged Access Never155machine # [ 0.005400] CPU features: detected: PMUv3156machine # [ 0.005403] CPU features: detected: RAS Extension Support157machine # [ 0.005406] CPU features: detected: RASv1p1 Extension Support158machine # [ 0.005409] CPU features: detected: Random Number Generator159machine # [ 0.005411] CPU features: detected: Speculation barrier (SB)160machine # [ 0.005414] CPU features: detected: Stage-2 Force Write-Back161machine # [ 0.005417] CPU features: detected: TLB range maintenance instructions162machine # [ 0.005421] CPU features: detected: Speculative Store Bypassing Safe (SSBS)163machine # [ 0.005458] alternatives: applying system-wide alternatives164machine # [ 0.008658] CPU features: detected: BBM Level 2 without TLB conflict abort165machine # [ 0.008799] Memory: 893724K/1048576K available (24448K kernel code, 7094K rwdata, 26592K rodata, 4736K init, 1107K bss, 113536K reserved, 32768K cma-reserved)166machine # [ 0.009158] devtmpfs: initialized167machine # [ 0.010961] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)168machine # [ 0.010982] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).169machine # [ 0.011166] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL170machine # [ 0.011170] 0 pages in range for non-PLT usage171machine # [ 0.011172] 508224 pages in range for PLT usage172machine # [ 0.011269] pinctrl core: initialized pinctrl subsystem173machine # [ 0.012129] DMI not present or invalid.174machine # [ 0.015423] NET: Registered PF_NETLINK/PF_ROUTE protocol family175machine # [ 0.017861] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations176machine # [ 0.018102] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations177machine # [ 0.018270] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations178machine # [ 0.018295] audit: initializing netlink subsys (disabled)179machine # [ 0.018943] thermal_sys: Registered thermal governor 'fair_share'180machine # [ 0.018946] thermal_sys: Registered thermal governor 'bang_bang'181machine # [ 0.018949] thermal_sys: Registered thermal governor 'step_wise'182machine # [ 0.018952] thermal_sys: Registered thermal governor 'user_space'183machine # [ 0.018955] thermal_sys: Registered thermal governor 'power_allocator'184machine # [ 0.018990] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1185machine # [ 0.018998] cpuidle: using governor ladder186machine # [ 0.019004] cpuidle: using governor menu187machine # [ 0.019214] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.188machine # [ 0.019231] ASID allocator initialised with 65536 entries189machine # [ 0.020463] Serial: AMBA PL011 UART driver190machine # [ 0.026153] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1191machine # [ 0.026289] printk: console [ttyAMA0] enabled192machine # [ 0.160866] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages193machine # [ 0.160876] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page194machine # [ 0.160880] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages195machine # [ 0.160883] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page196machine # [ 0.160886] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages197machine # [ 0.160889] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page198machine # [ 0.160892] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages199machine # [ 0.160895] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page200machine # [ 0.162187] fbcon: Taking over console201machine # [ 0.162197] ACPI: Interpreter disabled.202machine # [ 0.172425] iommu: Default domain type: Translated203machine # [ 0.172432] iommu: DMA domain TLB invalidation policy: strict mode204machine # [ 0.172834] SCSI subsystem initialized205machine # [ 0.175022] usbcore: registered new interface driver usbfs206machine # [ 0.175053] usbcore: registered new interface driver hub207machine # [ 0.175070] usbcore: registered new device driver usb208machine # [ 0.175378] pps_core: LinuxPPS API ver. 1 registered209machine # [ 0.175381] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>210machine # [ 0.175389] PTP clock support registered211machine # [ 0.175437] EDAC MC: Ver: 3.0.0212machine # [ 0.175637] scmi_core: SCMI protocol bus registered213machine # [ 0.182494] FPGA manager framework214machine # [ 0.183167] vgaarb: loaded215machine # [ 0.186236] clocksource: Switched to clocksource arch_sys_counter216machine # [ 0.189997] VFS: Disk quotas dquot_6.6.0217machine # [ 0.190030] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)218machine # [ 0.193922] netfs: FS-Cache loaded219machine # [ 0.194137] pnp: PnP ACPI: disabled220machine # [ 0.199108] NET: Registered PF_INET protocol family221machine # [ 0.199265] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)222machine # [ 0.231365] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)223machine # [ 0.231409] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)224machine # [ 0.231434] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)225machine # [ 0.234788] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)226machine # [ 0.234864] TCP: Hash tables configured (established 8192 bind 8192)227machine # [ 0.234956] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)228machine # [ 0.234988] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)229machine # [ 0.235011] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)230machine # [ 0.235101] NET: Registered PF_UNIX/PF_LOCAL protocol family231machine # [ 0.235120] NET: Registered PF_XDP protocol family232machine # [ 0.235137] PCI: CLS 0 bytes, default 64233machine # [ 0.235401] Trying to unpack rootfs image as initramfs...234machine # [ 0.247485] kvm [1]: HYP mode not available235machine # [ 0.348067] Initialise system trusted keyrings236machine # [ 0.348377] workingset: timestamp_bits=42 max_order=18 bucket_order=0237machine # [ 0.348748] squashfs: version 4.0 (2009/01/31) Phillip Lougher238machine # [ 0.348794] 9p: Installing v9fs 9p2000 file system support239machine # [ 0.372040] Key type asymmetric registered240machine # [ 0.372053] Asymmetric key parser 'x509' registered241machine # [ 0.372145] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)242machine # [ 0.383546] io scheduler mq-deadline registered243machine # [ 0.383559] io scheduler kyber registered244machine # [ 0.395633] pl061_gpio 9030000.pl061: PL061 GPIO chip registered245machine # [ 0.396395] ledtrig-cpu: registered to indicate activity on CPUs246machine # [ 0.396827] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:247machine # [ 0.396842] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000248machine # [ 0.396852] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000249machine # [ 0.396858] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000250machine # [ 0.396882] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits251machine # [ 0.396910] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]252machine # [ 0.397003] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00253machine # [ 0.397010] pci_bus 0000:00: root bus resource [bus 00-ff]254machine # [ 0.397014] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]255machine # [ 0.397017] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]256machine # [ 0.397020] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]257machine # [ 0.397120] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint258machine # [ 0.397616] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint259machine # [ 0.397813] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]260machine # [ 0.397825] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]261machine # [ 0.397851] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]262machine # [ 0.397863] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]263machine # [ 0.398434] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint264machine # [ 0.398624] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]265machine # [ 0.398635] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]266machine # [ 0.398661] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]267machine # [ 0.399156] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint268machine # [ 0.399349] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]269machine # [ 0.399361] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]270machine # [ 0.399387] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]271machine # [ 0.425156] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint272machine # [ 0.425344] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]273machine # [ 0.425355] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]274machine # [ 0.425381] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]275machine # [ 0.425393] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]276machine # [ 0.425886] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint277machine # [ 0.426090] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]278machine # [ 0.426116] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]279machine # [ 0.426601] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint280machine # [ 0.426791] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]281machine # [ 0.426819] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282machine # [ 0.427226] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint283machine # [ 0.427419] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]284machine # [ 0.443763] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint285machine # [ 0.443970] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]286machine # [ 0.443999] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]287machine # [ 0.444491] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint288machine # [ 0.444685] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]289machine # [ 0.444711] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]290machine # [ 0.445187] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint291machine # [ 0.445380] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]292machine # [ 0.445406] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]293machine # [ 0.445894] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint294machine # [ 0.446195] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]295machine # [ 0.446207] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]296machine # [ 0.446235] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]297machine # [ 0.446730] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint298machine # [ 0.446919] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]299machine # [ 0.446932] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]300machine # [ 0.446959] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]301machine # [ 0.467638] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned302machine # [ 0.467647] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned303machine # [ 0.467651] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned304machine # [ 0.467700] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned305machine # [ 0.467744] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned306machine # [ 0.467790] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned307machine # [ 0.467835] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned308machine # [ 0.467886] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned309machine # [ 0.467933] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned310machine # [ 0.467976] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned311machine # [ 0.468020] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned312machine # [ 0.468065] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned313machine # [ 0.468162] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned314machine # [ 0.468209] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned315machine # [ 0.468226] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned316machine # [ 0.468243] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned317machine # [ 0.468261] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned318machine # [ 0.468278] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned319machine # [ 0.468298] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned320machine # [ 0.468317] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned321machine # [ 0.468335] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned322machine # [ 0.468360] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned323machine # [ 0.468379] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned324machine # [ 0.468397] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned325machine # [ 0.468416] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned326machine # [ 0.468433] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned327machine # [ 0.468450] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned328machine # [ 0.468469] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned329machine # [ 0.468487] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned330machine # [ 0.468504] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned331machine # [ 0.468523] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned332machine # [ 0.468545] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]333machine # [ 0.468550] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]334machine # [ 0.468553] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]335machine # [ 0.469456] pci 0000:00:07.0: enabling device (0000 -> 0002)336machine # [ 0.530451] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)337machine # [ 0.533879] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)338machine # [ 0.544161] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)339machine # [ 0.546468] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)340machine # [ 0.550636] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)341machine # [ 0.553902] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)342machine # [ 0.564936] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)343machine # [ 0.566884] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)344machine # [ 0.577214] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)345machine # [ 0.579242] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)346machine # [ 0.583420] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)347machine # [ 0.596125] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled348machine # [ 0.601452] msm_serial: driver initialized349machine # [ 0.601612] SuperH (H)SCI(F) driver initialized350machine # [ 0.601669] STM32 USART driver initialized351machine # [ 0.638629] loop: module loaded352machine # [ 0.638861] virtio_blk virtio2: 1/0/0 default/read/poll queues353machine # [ 0.641028] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)354machine # [ 0.648266] megasas: 07.734.00.00-rc1355machine # [ 0.649048] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]356machine # [ 0.659547] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000357machine # [ 0.659579] Intel/Sharp Extended Query Table at 0x0031358machine # [ 0.662174] Using buffer write method359machine # [ 0.662234] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]360machine # [ 0.667411] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000361machine # [ 0.667431] Intel/Sharp Extended Query Table at 0x0031362machine # [ 0.676622] Using buffer write method363machine # [ 0.676652] Concatenating MTD devices:364machine # [ 0.676654] (0): "0.flash"365machine # [ 0.676657] (1): "0.flash"366machine # [ 0.676659] into device "0.flash"367machine # [ 0.961999] Freeing initrd memory: 26424K368machine # [ 0.968339] tun: Universal TUN/TAP device driver, 1.6369machine # [ 0.972531] thunder_xcv, ver 1.0370machine # [ 0.972560] thunder_bgx, ver 1.0371machine # [ 0.972603] nicpf, ver 1.0372machine # [ 0.973183] e1000: Intel(R) PRO/1000 Network Driver373machine # [ 0.973187] e1000: Copyright (c) 1999-2006 Intel Corporation.374machine # [ 0.973211] e1000e: Intel(R) PRO/1000 Network Driver375machine # [ 0.973216] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.376machine # [ 0.973244] igb: Intel(R) Gigabit Ethernet Network Driver377machine # [ 0.973247] igb: Copyright (c) 2007-2014 Intel Corporation.378machine # [ 0.973268] igbvf: Intel(R) Gigabit Virtual Function Network Driver379machine # [ 0.973271] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.380machine # [ 0.973411] sky2: driver version 1.30381machine # [ 0.975078] usbcore: registered new interface driver usb-storage382machine # [ 0.975123] usbcore: registered new interface driver usbserial_generic383machine # [ 0.975134] usbserial: USB Serial support registered for generic384machine # [ 0.976133] ehci-pci 0000:00:07.0: EHCI Host Controller385machine # [ 0.976155] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1386machine # [ 0.976364] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000387machine # [ 0.989195] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00388machine # [ 0.989515] hub 1-0:1.0: USB hub found389machine # [ 0.989566] hub 1-0:1.0: 6 ports detected390machine # [ 0.990330] hv_vmbus: registering driver hyperv_keyboard391machine # [ 0.991209] rtc-pl031 9010000.pl031: registered as rtc0392machine # [ 0.991235] rtc-pl031 9010000.pl031: setting system clock to 2026-09-27T03:13:15 UTC (1790478795)393machine # [ 0.995915] i2c_dev: i2c /dev entries driver394machine # [ 0.999182] sdhci: Secure Digital Host Controller Interface driver395machine # [ 0.999188] sdhci: Copyright(c) Pierre Ossman396machine # [ 1.001439] Synopsys Designware Multimedia Card Interface Driver397machine # [ 1.001833] sdhci-pltfm: SDHCI platform and OF driver helper398machine # [ 1.003394] hid: raw HID events driver (C) Jiri Kosina399machine # [ 1.005852] usbcore: registered new interface driver usbhid400machine # [ 1.005860] usbhid: USB HID core driver401machine # [ 1.007019] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available402machine # [ 1.011075] drop_monitor: Initializing network drop monitor service403machine # [ 1.011211] NET: Registered PF_INET6 protocol family404machine # [ 1.013518] Segment Routing with IPv6405machine # [ 1.013532] In-situ OAM (IOAM) with IPv6406machine # [ 1.013558] NET: Registered PF_PACKET protocol family407machine # [ 1.013617] 9pnet: Installing 9P2000 support408machine # [ 1.013659] Key type dns_resolver registered409machine # [ 1.022483] registered taskstats version 1410machine # [ 1.022614] Loading compiled-in X.509 certificates411machine # [ 1.031363] Demotion targets for Node 0: null412machine # [ 1.032103] Key type .fscrypt registered413machine # [ 1.032111] Key type fscrypt-provisioning registered414machine # [ 1.032210] ima: No TPM chip found, activating TPM-bypass!415machine # [ 1.032227] ima: Allocated hash algorithm: sha1416machine # [ 1.032247] ima: No architecture policies found417machine # [ 1.032833] input: gpio-keys as /devices/platform/gpio-keys/input/input0418machine # [ 1.055013] clk: Disabling unused clocks419machine # [ 1.055029] PM: genpd: Disabling unused power domains420machine # [ 1.059251] Freeing unused kernel memory: 4736K421machine # [ 1.059457] Run /init as init process422machine # [ 1.073740] systemd[1]: Successfully made /usr/ read-only.423machine # [ 1.074272] systemd[1]: systemd 263-885fe07 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)424machine # [ 1.074285] systemd[1]: Detected virtualization qemu.425machine # [ 1.074325] systemd[1]: Detected architecture arm64.426machine # [ 1.074329] systemd[1]: Running in initrd.427machine # [ 1.074610] systemd[1]: Initializing machine ID from random generator.428machine # [ 1.074701] systemd[1]: Hostname set to <machine>.429machine # [ 1.172851] systemd[1]: bpf-restrict-fs: LSM BPF program attached430machine # [ 1.224931] systemd[1]: Queued start job for default target Initrd Default Target.431machine # [ 1.233397] systemd[1]: Created slice Slice /system/modprobe.432machine # [ 1.233562] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.433machine # [ 1.233586] systemd[1]: Expecting device /dev/disk/by-label/nixos...434machine # [ 1.233604] systemd[1]: Reached target Path Units.435machine # [ 1.233614] systemd[1]: Reached target Slice Units.436machine # [ 1.233625] systemd[1]: Reached target Swaps.437machine # [ 1.233636] systemd[1]: Reached target Timer Units.438machine # [ 1.233755] systemd[1]: Listening on D-Bus System Message Bus Socket.439machine # [ 1.233866] systemd[1]: Listening on Journal Socket (/dev/log).440machine # [ 1.234083] systemd[1]: Listening on Journal Sockets.441machine # [ 1.234155] systemd[1]: Listening on udev Kernel Socket.442machine # [ 1.234166] systemd[1]: Reached target Socket Units.443machine # [ 1.235396] systemd[1]: Starting Create List of Static Device Nodes...444machine # [ 1.235451] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs445machine # [ 1.239613] usb 1-1: new high-speed USB device number 2 using ehci-pci446machine # [ 1.255629] systemd[1]: Mounting Kernel Configuration File System...447machine # [ 1.263653] systemd[1]: Starting Journal Service...448machine # [ 1.278400] systemd[1]: Starting Load Kernel Modules...449machine # [ 1.278506] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os450machine # [ 1.288698] systemd[1]: Starting Coldplug All udev Devices...451machine # [ 1.311619] systemd[1]: Finished Create List of Static Device Nodes.452machine # [ 1.316844] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...453machine # [ 1.317096] systemd[1]: Mounted Kernel Configuration File System.454machine # [ 1.325872] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.455machine # [ 1.338596] systemd-journald[71]: Collecting audit messages is disabled.456machine # [ 1.343862] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev457machine # [ 1.362395] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0458machine # [ 1.362642] [drm] features: -virgl +edid -resource_blob -host_visible459machine # [ 1.362646] [drm] features: -context_init460machine # [ 1.363353] [drm] number of scanouts: 1461machine # [ 1.363364] [drm] number of cap sets: 0462machine # [ 1.380062] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.463machine # [ 1.381727] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic464machine # [ 1.381738] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0465machine # [ 1.386680] systemd[1]: Starting Create Static Device Nodes in /dev...466machine # [ 1.402345] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1467machine # [ 1.411828] Console: switching to colour frame buffer device 160x50468machine # [ 1.436073] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device469machine # [ 1.448129] systemd[1]: Finished Create Static Device Nodes in /dev.470machine # [ 1.448398] systemd[1]: Reached target Preparation for Local File Systems.471machine # [ 1.448428] systemd[1]: Reached target Local File Systems.472machine # [ 1.450145] systemd[1]: Starting Rule-based Manager for Device Events and Files...473machine # [ 1.455087] systemd[1]: Finished Load Kernel Modules.474machine # [ 1.483716] systemd[1]: Starting Apply Kernel Variables...475machine # [ 1.487712] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0476machine # [ 1.519834] systemd[1]: Started Journal Service.477machine # [ 1.509780] systemd-modules-load[72]: Inserted module 'dm_mod'478machine # [ 1.511086] systemd-modules-load[72]: Module 'virtio_balloon' is built in479machine # [ 1.518930] systemd-modules-load[72]: Module 'virtio_console' is built in480machine # [ 1.524239] systemd-modules-load[72]: Inserted module 'virtio_gpu'481machine # [ 1.528339] systemd-modules-load[72]: Module 'virtio_rng' is built in482machine # [ 1.530553] systemd[1]: Finished Apply Kernel Variables.483machine # [ 1.536780] systemd[1]: Starting Create System Files and Directories...484machine # [ 1.560391] systemd-udevd[79]: Using default interface naming scheme 'v261'.485machine # [ 1.575985] systemd[1]: Finished Create System Files and Directories.486machine # [ 1.590105] systemd[1]: Started Rule-based Manager for Device Events and Files.487machine # [ 1.607562] usb 1-2: new high-speed USB device number 3 using ehci-pci488machine # [ 1.664285] systemd[1]: Starting Virtual Console Setup...489machine # [ 1.739098] systemd-vconsole-setup[102]: Configuration of first virtual console was skipped, ignoring remaining ones.490machine # [ 1.742943] systemd[1]: Finished Virtual Console Setup.491machine # [ 1.766745] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2492machine # [ 1.766998] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0493machine # [ 2.406206] systemd[1]: Finished Coldplug All udev Devices.494machine # [ 2.407244] systemd[1]: Reached target System Initialization.495machine # [ 2.408141] systemd[1]: Reached target Basic System.496machine # [ 2.547754] (udev-worker)[93]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.497machine # [ 2.586842] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.498machine # [ 2.643964] systemd[1]: Found device /dev/disk/by-label/nixos.499machine # [ 2.646411] systemd[1]: Reached target Initrd Root Device.500machine # [ 2.650322] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...501machine # [ 2.706368] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks502machine # [ 2.714957] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.503machine # [ 2.721408] systemd[1]: Mounting /sysroot...504machine # [ 2.771365] EXT4-fs (vda): mounted filesystem de6f5adc-a7c2-4a41-976a-d69787b518d5 r/w with ordered data mode. Quota mode: none.505machine # [ 2.761241] systemd[1]: Mounted /sysroot.506machine # [ 2.764120] systemd[1]: Reached target Initrd Root File System.507machine # [ 2.769397] systemd[1]: Starting Mountpoints Configured in the Real Root...508machine # [ 2.797735] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.509machine # [ 2.805499] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...510machine # [ 2.808874] systemd[1]: Reloading...511machine # [ 3.001550] systemd[1]: Reloading finished in 195 ms.512machine # [ 3.031445] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...513machine # [ 3.037692] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...514machine # [ 3.043664] systemd[1]: initrd-parse-etc.service: Deactivated successfully.515machine # [ 3.046811] systemd[1]: Finished Mountpoints Configured in the Real Root.516machine # [ 3.048493] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.517machine # [ 3.282128] systemd[1]: Mounting /sysroot/nix/.ro-store...518machine # [ 3.300257] systemd[1]: Mounting /sysroot/nix/.rw-store...519machine # [ 3.304935] systemd[1]: Mounting /sysroot/run...520machine # [ 3.323950] systemd[1]: Mounting /sysroot/tmp/shared...521machine # [ 3.348226] systemd[1]: Mounting /sysroot/tmp/xchg...522machine # [ 3.379643] systemd[1]: Mounted /sysroot/nix/.rw-store.523machine # [ 3.428400] systemd[1]: Starting rw-sysroot-nix-store.service...524machine # [ 3.433805] systemd[1]: Mounted /sysroot/run.525machine # [ 3.466906] fuse: init (API version 7.45)526machine # [ 3.485443] virtiofs virtio6: discovered new tag: nix-store527machine # [ 3.486370] virtiofs virtio6: virtio_fs_setup_dax: No cache capability528machine # [ 3.476791] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.529machine # [ 3.478316] systemd[1]: Finished rw-sysroot-nix-store.service.530machine # [ 3.501372] virtiofs virtio7: discovered new tag: shared531machine # [ 3.502302] virtiofs virtio7: virtio_fs_setup_dax: No cache capability532machine # [ 3.508707] virtiofs virtio8: discovered new tag: xchg533machine # [ 3.509487] virtiofs virtio8: virtio_fs_setup_dax: No cache capability534machine # [ 3.504078] systemd[1]: Mounted /sysroot/nix/.ro-store.535machine # [ 3.511923] systemd[1]: Mounted /sysroot/tmp/shared.536machine # [ 3.515677] systemd[1]: Mounted /sysroot/tmp/xchg.537machine # [ 3.963345] (udev-worker)[93]: mtd0ro: Failed to find and pin callout binary "/nix/store/h0gamn89nms8prhnc5v8m85jslcyklhg-systemd-263-885fe07/lib/udev/mtd_probe": No such file or directory538machine # [ 3.972580] (udev-worker)[93]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory539machine # [ 4.002968] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.540machine # [ 4.004320] systemd[1]: Stopped Virtual Console Setup.541machine # [ 4.008761] systemd[1]: Stopping Virtual Console Setup...542machine # [ 4.010430] systemd[1]: Starting Virtual Console Setup...543machine # [ 4.024865] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.544machine # [ 4.027647] systemd[1]: Stopped Virtual Console Setup.545machine # [ 4.029047] systemd[1]: Starting Virtual Console Setup...546machine # [ 4.053126] systemd-vconsole-setup[156]: Configuration of first virtual console was skipped, ignoring remaining ones.547machine # [ 4.056798] systemd[1]: Finished Virtual Console Setup.548machine # [ 4.280808] systemd[1]: Mounting /sysroot/nix/store...549machine # [ 4.324561] systemd[1]: Mounted /sysroot/nix/store.550machine # [ 4.327714] systemd[1]: Reached target Initrd File Systems.551machine # [ 4.329643] systemd[1]: Starting Find NixOS closure...552machine # [ 4.340262] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...553machine # [ 4.372737] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.554machine # [ 4.379062] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.555machine # [ 4.388274] systemd[1]: Finished Find NixOS closure.556machine # [ 4.391560] systemd[1]: Reached target Initrd Default Target.557machine # [ 4.396426] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...558machine # [ 4.421862] systemd[1]: initrd-cleanup.service: Deactivated successfully.559machine # [ 4.423569] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.560machine # [ 4.427388] systemd[1]: Stopped target Initrd Default Target.561machine # [ 4.428784] systemd[1]: Stopped target Basic System.562machine # [ 4.429580] systemd[1]: Stopped target Initrd Root Device.563machine # [ 4.432363] systemd[1]: Stopped target Path Units.564machine # [ 4.433235] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.565machine # [ 4.435613] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.566machine # [ 4.439485] systemd[1]: Stopped target Slice Units.567machine # [ 4.445264] systemd[1]: Stopped target Socket Units.568machine # [ 4.446121] systemd[1]: Stopped target System Initialization.569machine # [ 4.447084] systemd[1]: Stopped target Swaps.570machine # [ 4.447794] systemd[1]: Stopped target Timer Units.571machine # [ 4.457545] systemd[1]: dbus.socket: Deactivated successfully.572machine # [ 4.459880] systemd[1]: Closed D-Bus System Message Bus Socket.573machine # [ 4.461301] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.574machine # [ 4.462437] systemd[1]: Stopped Find NixOS closure.575machine # [ 4.463345] systemd[1]: Starting rw-sysroot-nix-store.service...576machine # [ 4.469981] systemd[1]: systemd-sysctl.service: Deactivated successfully.577machine # [ 4.471337] systemd[1]: Stopped Apply Kernel Variables.578machine # [ 4.480524] systemd[1]: systemd-modules-load.service: Deactivated successfully.579machine # [ 4.481759] systemd[1]: Stopped Load Kernel Modules.580machine # [ 4.482566] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.581machine # [ 4.483738] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.582machine # [ 4.490167] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.583machine # [ 4.492270] systemd[1]: Stopped Create System Files and Directories.584machine # [ 4.496345] systemd[1]: Stopped target Local File Systems.585machine # [ 4.497351] systemd[1]: Stopped target Preparation for Local File Systems.586machine # [ 4.499248] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.587machine # [ 4.500880] systemd[1]: Stopped Coldplug All udev Devices.588machine # [ 4.501756] systemd[1]: Stopping Rule-based Manager for Device Events and Files...589machine # [ 4.502959] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.590machine # [ 4.504164] systemd[1]: Stopped Virtual Console Setup.591machine # [ 4.504960] systemd[1]: systemd-udevd.service: Deactivated successfully.592machine # [ 4.505951] systemd[1]: Stopped Rule-based Manager for Device Events and Files.593machine # [ 4.507010] systemd[1]: systemd-udevd.service: Consumed 1.528s CPU time over 3.039s wall clock time, 22.4M memory peak.594machine # [ 4.512399] systemd[1]: Starting Cleanup udev Database...595machine # [ 4.513335] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.596machine # [ 4.516827] systemd[1]: Stopped Create Static Device Nodes in /dev.597machine # [ 4.520194] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.598machine # [ 4.521458] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.599machine # [ 4.524188] systemd[1]: kmod-static-nodes.service: Deactivated successfully.600machine # [ 4.528135] systemd[1]: Stopped Create List of Static Device Nodes.601machine # [ 4.529149] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.602machine # [ 4.530196] systemd[1]: Finished rw-sysroot-nix-store.service.603machine # [ 4.557479] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.604machine # [ 4.560533] systemd[1]: Finished Cleanup udev Database.605machine # [ 4.562576] systemd[1]: Reached target Switch Root.606machine # [ 4.563759] systemd[1]: Starting NixOS Activation...607machine # [ 4.641400] initrd-nixos-activation-start[179]: booting system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test608machine # [ 4.674108] initrd-nixos-activation-start[179]: running activation script...609machine # [ 4.881345] initrd-nixos-activation-start[202]: setting up /etc...610machine # [ 4.994293] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.611machine # [ 4.997415] systemd[1]: Finished NixOS Activation.612machine # [ 4.998749] systemd[1]: Starting Switch Root...613machine # [ 5.020560] systemd[1]: Switching root.614machine # [ 5.201310] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).615machine # [ 5.352692] systemd[1]: systemd 263-885fe07 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)616machine # [ 5.353699] systemd[1]: Detected virtualization qemu.617machine # [ 5.354211] systemd[1]: Detected architecture arm64.618machine # [ 5.354591] systemd[1]: Detected first boot.619machine # [ 5.362505] systemd[1]: Initializing machine ID from random generator.620machine # [ 5.489118] systemd[1]: bpf-restrict-fs: LSM BPF program attached621machine # [ 5.632377] systemd[1]: Applying preset policy.622machine # [ 5.849750] systemd[1]: Populated /etc with preset unit settings.623machine # [ 6.057410] systemd[1]: initrd-switch-root.service: Deactivated successfully.624machine # [ 6.058291] systemd[1]: Stopped initrd-switch-root.service.625machine # [ 6.062797] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.626machine # [ 6.066668] systemd[1]: Created slice Slice /system/getty.627machine # [ 6.069240] systemd[1]: Created slice User and Session Slice.628machine # [ 6.069832] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.629machine # [ 6.070544] systemd[1]: Started Forward Password Requests to Wall Directory Watch.630machine # [ 6.071134] systemd[1]: Expecting device /dev/hvc0...631machine # [ 6.075845] systemd[1]: Expecting device /dev/ttyAMA0...632machine # [ 6.076298] systemd[1]: Reached target Local Encrypted Volumes.633machine # [ 6.076970] systemd[1]: Stopped target initrd-fs.target.634machine # [ 6.077594] systemd[1]: Stopped target initrd-root-fs.target.635machine # [ 6.078260] systemd[1]: Stopped target initrd-switch-root.target.636machine # [ 6.078901] systemd[1]: Reached target Virtual Machines and Containers.637machine # [ 6.085127] systemd[1]: Reached target Path Units.638machine # [ 6.085577] systemd[1]: Reached target Remote File Systems.639machine # [ 6.086343] systemd[1]: Reached target Slice Units.640machine # [ 6.087026] systemd[1]: Reached target Swaps.641machine # [ 6.092330] systemd[1]: Listening on Query the User Interactively for a Password.642machine # [ 6.094304] systemd[1]: Listening on Process Core Dump Socket.643machine # [ 6.097474] systemd[1]: Listening on Credential Encryption/Decryption.644machine # [ 6.098922] systemd[1]: Listening on Factory Reset Management.645machine # [ 6.099441] systemd[1]: Listening on Hostname Service Socket.646machine # [ 6.105300] systemd[1]: Starting Journal Log Access Socket...647machine # [ 6.106912] systemd[1]: Listening on Journal Audit Socket.648machine # [ 6.111094] systemd[1]: Listening on Console Output Muting Service Socket.649machine # [ 6.113533] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.650machine # [ 6.114954] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os651machine # [ 6.119363] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki652machine # [ 6.124204] systemd[1]: Listening on Disk Repartitioning Service Socket.653machine # [ 6.124798] systemd[1]: Listening on udev Varlink Socket.654machine # [ 6.129623] systemd[1]: Mounting Huge Pages File System...655machine # [ 6.136694] systemd[1]: Mounting POSIX Message Queue File System...656machine # [ 6.148191] systemd[1]: Mounting Kernel Debug File System...657machine # [ 6.163645] systemd[1]: Mounting Kernel Trace File System...658machine # [ 6.172807] systemd[1]: Starting Create List of Static Device Nodes...659machine # [ 6.176643] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs660machine # [ 6.192500] systemd[1]: Mounting Kernel Configuration File System...661machine # [ 6.193068] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm662machine # [ 6.200248] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore663machine # [ 6.205165] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse664machine # [ 6.242696] systemd[1]: Mounting FUSE Control File System...665machine # [ 6.243163] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67666machine # [ 6.274443] systemd[1]: Starting Journal Service...667machine # [ 6.294822] systemd[1]: Starting Load Kernel Modules...668machine # [ 6.317831] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...669machine # [ 6.345360] systemd[1]: Starting Remount Root and Kernel File Systems...670machine # [ 6.345788] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os671machine # [ 6.365143] systemd[1]: Starting Coldplug All udev Devices...672machine # [ 6.381406] systemd[1]: Listening on Journal Log Access Socket.673machine # [ 6.382146] systemd[1]: Mounted Huge Pages File System.674machine # [ 6.382598] systemd[1]: Mounted POSIX Message Queue File System.675machine # [ 6.382991] systemd[1]: Mounted Kernel Debug File System.676machine # [ 6.383375] systemd[1]: Mounted Kernel Trace File System.677machine # [ 6.403353] systemd-journald[273]: Collecting audit messages is enabled.678machine # [ 6.411956] systemd[1]: Finished Create List of Static Device Nodes.679machine # [ 6.405077] systemd[1]: Queued start job for default target Multi-User System.680machine # [ 6.408552] systemd[1]: systemd-journald.service: Deactivated successfully.681machine # [ 6.444020] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...682machine # [ 6.444744] systemd[1]: Started Journal Service.683machine # [ 6.438123] systemd[1]: Mounted Kernel Configuration File System.684machine # [ 6.446985] systemd-modules-load[274]: Module 'atkbd' is built in685machine # [ 6.451160] systemd-modules-load[274]: Module 'loop' is built in686machine # [ 6.477604] systemd-oomd[275]: No swap; memory pressure usage will be degraded687machine # [ 6.479302] systemd[1]: Finished Load Kernel Modules.688machine # [ 6.505236] EXT4-fs (vda): re-mounted de6f5adc-a7c2-4a41-976a-d69787b518d5.689machine # [ 6.496317] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.690machine # [ 6.506310] systemd[1]: Finished Remount Root and Kernel File Systems.691machine # [ 6.521136] systemd[1]: Mounted FUSE Control File System.692machine # [ 6.524721] systemd[1]: Listening on Disk Image Download Service Socket.693machine # [ 6.538079] systemd[1]: Starting Firewall...694machine # [ 6.540166] systemd[1]: Starting Flush Journal to Persistent Storage...695machine # [ 6.541245] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore696machine # [ 6.558217] systemd[1]: Starting Load/Save OS Random Seed...697machine # [ 6.588388] systemd[1]: Starting Apply Kernel Variables...698machine # [ 6.589438] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os699machine # [ 6.658589] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.700machine # [ 6.671765] systemd[1]: Starting Create Static Device Nodes in /dev...701machine # [ 6.696589] systemd-journald[273]: Received client request to flush runtime journal.702machine # [ 6.782635] systemd[1]: Finished Load/Save OS Random Seed.703machine # [ 6.783722] systemd[1]: Reached target First Boot Complete.704machine # [ 6.797874] systemd[1]: Finished Flush Journal to Persistent Storage.705machine # [ 6.817166] systemd[1]: Finished Apply Kernel Variables.706machine # [ 6.865953] systemd[1]: Finished Create Static Device Nodes in /dev.707machine # [ 6.873234] systemd[1]: Reached target Preparation for Local File Systems.708machine # [ 6.875940] systemd[1]: Starting Rule-based Manager for Device Events and Files...709machine # [ 7.028996] systemd-udevd[307]: Using default interface naming scheme 'v261'.710machine # [ 7.053290] systemd[1]: Mounting /run/wrappers...711machine # [ 7.118797] systemd[1]: Mounted /run/wrappers.712machine # [ 7.119748] systemd[1]: Reached target Local File Systems.713machine # [ 7.128906] systemd[1]: Listening on Boot Loader Control Service Socket.714machine # [ 7.135392] systemd[1]: Starting Create SUID/SGID Wrappers...715machine # [ 7.136770] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.716machine # [ 7.145516] systemd[1]: Starting Save Transient machine-id to Disk...717machine # [ 7.153009] systemd[1]: Starting Create System Files and Directories...718machine # [ 7.184583] systemd[1]: Started Rule-based Manager for Device Events and Files.719machine # [ 7.301572] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.720machine # [ 7.315666] systemd[1]: Finished Save Transient machine-id to Disk.721machine # [ 7.381039] systemd[1]: Finished Create System Files and Directories.722machine # [ 7.394611] systemd[1]: Starting Rebuild Journal Catalog...723machine # [ 7.401724] systemd[1]: Starting Record System Boot/Shutdown in UTMP...724machine # [ 7.545363] systemd[1]: Finished Record System Boot/Shutdown in UTMP.725machine # [ 7.555169] systemd[1]: Finished Rebuild Journal Catalog.726machine # [ 7.562248] systemd[1]: Starting Update is Completed...727machine # [ 7.638751] systemd[1]: Finished Update is Completed.728machine # [ 8.109659] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.729machine # [ 8.116219] systemd[1]: Finished Create SUID/SGID Wrappers.730machine # [ 8.157188] systemd[1]: Finished Coldplug All udev Devices.731machine # [ 8.158269] systemd[1]: Reached target System Initialization.732machine # [ 8.159271] systemd[1]: Started Discard unused filesystem blocks once a week.733machine # [ 8.170123] systemd[1]: Started Daily Cleanup of Temporary Directories.734machine # [ 8.171263] systemd[1]: Reached target Timer Units.735machine # [ 8.173094] systemd[1]: Listening on D-Bus System Message Bus Socket.736machine # [ 8.174129] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.737machine # [ 8.175556] systemd[1]: Reached target Socket Units.738machine # [ 8.184346] systemd[1]: Reached target Basic System.739machine # [ 8.185174] systemd[1]: Starting Import lastlog data into lastlog2 database...740machine # [ 8.186255] systemd[1]: Starting Name Service Cache Daemon (nsncd)...741machine # [ 8.187259] systemd[1]: Starting Post-Boot Actions...742machine # [ 8.202480] systemd[1]: Started Reset console on configuration changes.743machine # [ 8.221923] systemd[1]: Starting resolvconf update...744machine # [ 8.291645] systemd[1]: Starting D-Bus System Message Bus...745machine # [ 8.329359] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs746machine # [ 8.347359] nsncd[427]: Sep 27 03:13:22.864 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"747machine # [ 8.360633] systemd[1]: Started Name Service Cache Daemon (nsncd).748machine # [ 8.372623] systemd[1]: Reached target Host and Network Name Lookups.749machine # [ 8.373818] systemd[1]: Reached target User and Group Name Lookups.750machine # [ 8.408394] systemd[1]: Starting User Login Management...751machine # [ 8.421808] systemd[1]: Finished Post-Boot Actions.752machine # [ 8.446330] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse753machine # [ 8.502300] systemd[1]: Finished Import lastlog data into lastlog2 database.754machine # [ 8.614696] dbus-broker-launch[434]: Looking up NSS user entry for 'systemd-timesync'...755machine # [ 8.625733] dbus-broker-launch[434]: NSS returned no entry for 'systemd-timesync'756machine # [ 8.626983] dbus-broker-launch[434]: Invalid user-name in /nix/store/svs250hlinpmmh842v5vq65hzwi7rbkc-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"757machine # [ 8.664768] systemd[1]: Started D-Bus System Message Bus.758machine # [ 8.672936] systemd-logind[451]: New seat seat0.759machine # [ 8.682575] systemd[1]: Started User Login Management.760machine # [ 8.689073] systemd[1]: Starting linger-users.service...761machine # [ 8.730884] dbus-broker-launch[434]: Ready762machine # [ 8.772651] systemd[1]: Stopped target Host and Network Name Lookups.763machine # [ 8.773822] systemd[1]: Stopping Host and Network Name Lookups...764machine # [ 8.774805] systemd[1]: Stopped target User and Group Name Lookups.765machine # [ 8.775742] systemd[1]: Stopping User and Group Name Lookups...766machine # [ 8.796204] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...767machine # [ 8.801226] systemd[1]: nscd.service: Deactivated successfully.768machine # [ 8.802216] systemd[1]: Stopped Name Service Cache Daemon (nsncd).769machine # [ 8.835903] systemd[1]: Starting Name Service Cache Daemon (nsncd)...770machine # [ 8.883878] systemd[1]: Created slice Slice /user/1001.771machine # [ 8.904364] systemd[1]: Starting User Runtime Directory /run/user/1001...772machine # [ 8.947535] nsncd[520]: Sep 27 03:13:23.467 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"773machine # [ 8.963778] systemd[1]: Started Name Service Cache Daemon (nsncd).774machine # [ 8.977259] systemd[1]: linger-users.service: Deactivated successfully.775machine # [ 8.987038] systemd[1]: Finished linger-users.service.776machine # [ 8.996581] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.777machine # [ 9.013320] systemd[1]: Reached target Host and Network Name Lookups.778machine # [ 9.014503] systemd[1]: Reached target User and Group Name Lookups.779machine # [ 9.023370] systemd[1]: Finished resolvconf update.780machine # [ 9.033971] systemd[1]: Starting DHCP Client...781machine # [ 9.059585] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.782machine # [ 9.071568] systemd[1]: Started backdoor.service.783machine # [ 9.116207] systemd[1]: Finished User Runtime Directory /run/user/1001.784machine # [ 9.161300] systemd[1]: Starting User Manager for UID 1001...785machine # [ 9.247099] (udev-worker)[329]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.786machine # [ 9.293178] (udev-worker)[329]: Network interface NamePolicy= disabled on kernel command line.787machine # [ 9.331554] (systemd)[563]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)788machine # [ 9.380410] systemd-logind[451]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.789machine # [ 9.431894] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy790machine # connecting to host...791machine: Guest shell says: b'Spawning backdoor root shell...\n'792machine: connected to guest root shell793machine: (connecting took 10.06 seconds)794machine: (finished: waiting for the VM to finish booting, in 10.47 seconds)795machine # [ 9.796812] systemd[1]: Condition check resulted in Virtio network device being skipped.796machine # [ 9.836817] systemd-logind[451]: Watching system buttons on /dev/input/event0 (gpio-keys)797machine # [ 9.878568] mousedev: PS/2 mouse device common for all mice798machine # [ 9.886995] systemd[563]: Queued start job for default target Main User Target.799machine # [ 9.911354] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy800machine # [ 9.941470] systemd[563]: Created slice User Application Slice.801machine # [ 9.942624] systemd[563]: Started Daily Cleanup of User's Temporary Directories.802machine # [ 9.943798] systemd[563]: Reached target Paths.803machine # [ 9.991577] systemd[563]: Reached target Timers.804machine # [ 9.999850] systemd[563]: Starting D-Bus User Message Bus Socket...805machine # [ 10.009082] systemd[563]: Starting Create User Files and Directories...806machine # [ 10.010138] systemd[563]: Finished Create User Files and Directories.807machine # [ 10.011208] systemd[563]: Listening on D-Bus User Message Bus Socket.808machine # [ 10.031308] systemd[563]: Reached target Sockets.809machine # [ 10.039363] systemd[563]: Reached target Basic System.810machine # [ 10.042752] systemd[1]: Started User Manager for UID 1001.811machine # [ 10.043651] systemd[563]: Starting Run user-specific NixOS activation...812machine # [ 10.052513] systemd[563]: Finished Run user-specific NixOS activation.813machine # [ 10.053546] systemd[563]: Reached target Main User Target.814machine # [ 10.054364] systemd[563]: Startup finished in 498ms.815machine # [ 10.055196] systemd-logind[451]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)816machine # [ 10.095441] dhcpcd[581]: dhcpcd-10.3.2 starting817machine # [ 10.101647] dhcpcd[606]: dev: loaded udev818machine # [ 10.116442] systemd[1]: Finished Firewall.819machine # [ 10.127953] systemd[1]: Reached target Preparation for Network.820machine # [ 10.148018] 8021q: 802.1Q VLAN Support v1.8821machine # [ 10.137611] systemd[1]: Starting Address configuration of eth1...822machine # [ 10.140916] systemd[1]: Starting Extra networking commands....823machine # [ 10.245227] cfg80211: Loading compiled-in X.509 certificates for regulatory database824machine # [ 10.282824] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'825machine # [ 10.283369] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'826machine # [ 10.287725] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2827machine # [ 10.288067] cfg80211: failed to load regulatory.db828machine # [ 10.473710] 8021q: adding VLAN 0 to HW filter on device eth1829machine # [ 10.494938] dhcpcd[632]: /nix/store/1ki7bgdr0niilkg9jbkpcdaggbv0kb8a-openresolv-3.17.4/sbin/.resolvconf-wrapped: line 1250: kill: (622) - Operation not permitted830machine # [ 10.516406] dhcpcd[632]: .resolvconf-wrapped: clearing stale lock pid 622831machine # [ 10.528474] network-addresses-eth1-start[609]: adding address 192.168.1.1/24... done832machine # [ 10.595580] network-addresses-eth1-start[609]: adding address 2001:db8:1::1/64... done833machine # [ 10.701698] systemd[1]: Finished Address configuration of eth1.834machine # [ 10.736905] 8021q: adding VLAN 0 to HW filter on device eth0835machine # [ 10.730283] dhcpcd[606]: eth0: waiting for carrier836machine # [ 10.731135] dhcpcd[606]: eth0: carrier acquired837machine # [ 10.805273] dhcpcd[606]: DUID 00:01:00:01:32:4b:46:55:52:54:00:12:34:56838machine # [ 10.806536] dhcpcd[606]: eth0: IAID 00:12:34:56839machine # [ 10.807262] dhcpcd[606]: eth0: adding address fe80::5054:ff:fe12:3456840machine # [ 10.841653] dhcpcd[606]: eth0: soliciting a DHCP lease841machine # [ 10.856820] dhcpcd[606]: eth0: offered 10.0.2.15 from 10.0.2.2842machine # [ 10.857898] dhcpcd[606]: eth0: probing address 10.0.2.15/24843machine # [ 11.248582] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3844machine # [ 11.251035] systemd[1]: Finished Extra networking commands..845machine # [ 11.267354] systemd[1]: Reached target Network.846machine # [ 11.280312] systemd[1]: Starting Permit User Sessions...847machine # [ 11.522368] systemd[1]: Finished Permit User Sessions.848machine # [ 11.536746] systemd[1]: Started Getty on tty1.849machine # [ 11.537617] systemd[1]: Reached target Login Prompts.850machine # [ 11.704899] systemd[1]: Starting Virtual Console Setup...851machine # [ 11.705875] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.852machine # [ 11.762873] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.853machine # [ 11.768497] systemd[1]: Stopped Virtual Console Setup.854machine # [ 11.769401] systemd[1]: Starting Virtual Console Setup...855machine # [ 11.784435] systemd-logind[451]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)856machine # [ 11.886945] systemd-vconsole-setup[714]: Configuration of first virtual console was skipped, ignoring remaining ones.857machine # [ 11.894076] systemd[1]: Finished Virtual Console Setup.858machine # [ 13.080533] dhcpcd[606]: eth0: soliciting an IPv6 router859machine # [ 13.082615] dhcpcd[606]: eth0: Router Advertisement from fe80::2860machine # [ 13.084008] dhcpcd[606]: eth0: adding address fec0::5054:ff:fe12:3456/64861machine # [ 13.085733] dhcpcd[606]: eth0: adding route to fec0::/64862machine # [ 13.086948] dhcpcd[606]: eth0: adding default route via fe80::2863machine # [ 16.041473] dhcpcd[606]: eth0: leased 10.0.2.15 for 86400 seconds864machine # [ 16.041668] dhcpcd[606]: eth0: adding route to 10.0.2.0/24865machine # [ 16.041740] dhcpcd[606]: eth0: adding default route via 10.0.2.2866machine # [ 16.176158] systemd[1]: Started DHCP Client.867machine # [ 16.178146] systemd[1]: Reached target Multi-User System.868machine # [ 16.179041] systemd[1]: Startup finished in 1.048s (kernel) + 4.209s (initrd) + 10.919s (userspace) = 16.178s.869machine: (finished: waiting for unit multi-user.target, in 17.89 seconds)870machine: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test871machine # Checking switch inhibitors... done872machine # [ 17.357822] nixos[823]: switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test873machine # [ 17.360637] systemd[1]: Stopped target Remote File Systems.874machine # activating the configuration...875machine # [ 17.363311] systemd[1]: Stopped target Local File Systems.876machine # setting up /etc...877machine # [ 17.627375] systemd[1]: Reload requested from client PID 823 ('.switch-to-conf') (unit backdoor.service)...878machine # [ 17.629510] systemd[1]: Reloading...879machine # [ 17.934121] systemd[1]: Reloading finished in 303 ms.880machine # reloading user units for usertest...881machine # [ 17.986611] systemd[563]: Created slice User Core Session Slice.882machine # [ 17.989667] systemd[563]: Starting D-Bus User Message Bus...883machine # [ 18.022507] dbus-broker-launch[893]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/svs250hlinpmmh842v5vq65hzwi7rbkc-system-path/share/dbus-1/services/org.freedesktop.import1.service'884machine # [ 18.025752] dbus-broker-launch[893]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/svs250hlinpmmh842v5vq65hzwi7rbkc-system-path/share/dbus-1/services/org.freedesktop.machine1.service'885machine # [ 18.028746] dbus-broker-launch[893]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/svs250hlinpmmh842v5vq65hzwi7rbkc-system-path/share/dbus-1/services/org.freedesktop.portable1.service'886machine # [ 18.031709] dbus-broker-launch[893]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/svs250hlinpmmh842v5vq65hzwi7rbkc-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'887machine # [ 18.036371] dbus-broker-launch[893]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored888machine # [ 18.038093] dbus-broker-launch[893]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored889machine # [ 18.039820] systemd[563]: Started D-Bus User Message Bus.890machine # [ 18.045464] dbus-broker-launch[893]: Ready891machine # [ 18.083526] systemd[563]: Reexecution requested from client PID 892 ('switch-to-confi')...892machine # [ 18.087262] systemd[563]: Reexecuting.893machine # [ 18.147846] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy894machine # [ 18.265887] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy895machine # [ 18.272808] systemd[563]: Reload requested from client PID 892 ('switch-to-confi')...896machine # [ 18.276093] systemd[563]: Reloading...897machine # [ 18.384375] systemd[563]: Reloading finished in 108 ms.898machine # [ 18.401634] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy899machine # restarting the following user units: nixos-activation.service900machine # [ 18.410127] systemd[563]: Stopped Run user-specific NixOS activation.901machine # [ 18.412520] systemd[563]: Stopping Run user-specific NixOS activation...902machine # [ 18.414716] systemd[563]: Starting Run user-specific NixOS activation...903machine # [ 18.447539] systemd[563]: Finished Run user-specific NixOS activation.904machine # restarting sysinit-reactivation.target905machine # [ 18.459054] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....906machine # [ 18.544158] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..907machine # [ 18.547733] systemd[1]: Reached target Reactivate sysinit units.908machine # [ 18.551888] systemd[1]: Reached target Remote File Systems.909machine # [ 18.556716] systemd[1]: Starting linger-users.service...910machine # [ 18.563368] systemd[1]: Reached target Local File Systems.911machine # [ 18.570082] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore912machine # [ 18.571818] systemd[1]: Starting Create SUID/SGID Wrappers...913machine # [ 18.583906] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.914machine # [ 18.588368] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67915machine # [ 18.590891] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore916machine # [ 18.592750] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os917machine # [ 18.594116] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os918machine # [ 18.595392] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os919machine # [ 18.602790] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki920machine # [ 18.653896] systemd[1]: linger-users.service: Deactivated successfully.921machine # [ 18.656857] systemd[1]: Finished linger-users.service.922machine # [ 18.786218] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.923machine # [ 18.789968] systemd[1]: Finished Create SUID/SGID Wrappers.924machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service925machine # [ 19.046690] nixos[823]: finished switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test926machine: (finished: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test, in 1.91 seconds)927machine: must succeed: rm /etc/NIXOS928machine: (finished: must succeed: rm /etc/NIXOS, in 0.03 seconds)929machine: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/c014jg844hqw9kdghvk21k5i9sv26551-nixos-system-other-test/bin/switch-to-configuration test930machine # this should succeed (config: /nix/store/c014jg844hqw9kdghvk21k5i9sv26551-nixos-system-other-test, action: test)931machine # Checking switch inhibitors... done932machine # [ 19.311796] nixos[970]: switching to system configuration /nix/store/c014jg844hqw9kdghvk21k5i9sv26551-nixos-system-other-test933machine # [ 19.314611] systemd[1]: Stopped target Local File Systems.934machine # activating the configuration...935machine # [ 19.317479] systemd[1]: Stopped target Remote File Systems.936machine # removing user ‘usertest’937machine # setting up /etc...938machine # [ 19.625630] systemd[1]: Reload requested from client PID 970 ('.switch-to-conf') (unit backdoor.service)...939machine # [ 19.627436] systemd[1]: Reloading...940machine # [ 19.978441] systemd[1]: Reloading finished in 349 ms.941machine # reloading user units for usertest...942machine # [ 20.072242] systemd[563]: Reexecution requested from client PID 1041 ('switch-to-confi')...943machine # [ 20.073789] systemd[563]: Reexecuting.944machine # [ 20.127625] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy945machine # [ 20.283260] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy946machine # [ 20.290550] systemd[563]: Reload requested from client PID 1041 ('switch-to-confi')...947machine # [ 20.293252] systemd[563]: Reloading...948machine # [ 20.406683] systemd[563]: Reloading finished in 112 ms.949machine # [ 20.425201] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy950machine # restarting the following user units: nixos-activation.service951machine # [ 20.438071] systemd[563]: Stopped Run user-specific NixOS activation.952machine # [ 20.440970] systemd[563]: Stopping Run user-specific NixOS activation...953machine # [ 20.443737] systemd[563]: Starting Run user-specific NixOS activation...954machine # [ 20.479218] systemd[563]: Finished Run user-specific NixOS activation.955machine # restarting sysinit-reactivation.target956machine # [ 20.484433] systemd[1]: Stopped target Reactivate sysinit units.957machine # reloading the following units: dbus-broker.service958machine # [ 20.488842] systemd[1]: Stopping Reactivate sysinit units...959machine # [ 20.489856] systemd[1]: Reached target Reactivate sysinit units.960machine # [ 20.490786] systemd[1]: Reloading D-Bus System Message Bus...961machine # [ 20.494794] dbus-broker-launch[434]: Caught SIGHUP, trigger reload.962machine # [ 20.499354] dbus-broker-launch[434]: Looking up NSS user entry for 'systemd-timesync'...963machine # [ 20.501758] dbus-broker-launch[434]: NSS returned no entry for 'systemd-timesync'964machine # [ 20.502892] dbus-broker-launch[434]: Invalid user-name in /nix/store/4wwlicycgp0fjbv9vh5f4m0xw5da8ac9-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"965machine # restarting the following units: network-addresses-eth1.service966machine # [ 20.520174] systemd[1]: Reloaded D-Bus System Message Bus.967machine # [ 20.524964] systemd[1]: Stopping Address configuration of eth1...968machine # [ 20.557870] network-addresses-eth1-pre-stop[1054]: deleting address 192.168.1.1/24... done969machine # [ 20.565380] network-addresses-eth1-pre-stop[1054]: deleting address 2001:db8:1::1/64... done970machine # [ 20.573248] systemd[1]: network-addresses-eth1.service: Deactivated successfully.971machine # [ 20.575606] systemd[1]: Stopped Address configuration of eth1.972machine # [ 20.581339] systemd[1]: Starting Address configuration of eth1...973machine # [ 20.630176] network-addresses-eth1-start[1059]: adding address 192.168.1.2/24... done974machine # [ 20.637015] network-addresses-eth1-start[1059]: adding address 2001:db8:1::2/64... done975machine # [ 20.646516] systemd[1]: Finished Address configuration of eth1.976machine # [ 20.651054] systemd[1]: Reached target Local File Systems.977machine # [ 20.654824] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore978machine # [ 20.659402] systemd[1]: Starting Create SUID/SGID Wrappers...979machine # [ 20.664533] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.980machine # [ 20.665923] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67981machine # [ 20.680144] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore982machine # [ 20.681898] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os983machine # [ 20.683257] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os984machine # [ 20.684684] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os985machine # [ 20.686094] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki986machine # [ 20.687512] systemd[1]: Reached target Remote File Systems.987machine # [ 20.867404] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.988machine # [ 20.872689] systemd[1]: Finished Create SUID/SGID Wrappers.989machine # [ 20.874870] systemd[1]: Starting linger-users.service...990machine # [ 20.907276] linger-users-start[1131]: id: ‘usertest’: no such user991machine # [ 20.909545] linger-users-start[1129]: Removing linger for missing user usertest992machine # [ 20.916432] systemd[1]: linger-users.service: Deactivated successfully.993machine # [ 20.919025] systemd[1]: Finished linger-users.service.994machine # [ 21.174255] nixos[970]: finished switching to system configuration /nix/store/c014jg844hqw9kdghvk21k5i9sv26551-nixos-system-other-test995machine: (finished: must succeed: /nix/store/glw2b718093b6m9v90xl0blwv4z9hk4p-stderr-runner /nix/store/c014jg844hqw9kdghvk21k5i9sv26551-nixos-system-other-test/bin/switch-to-configuration test, in 2.10 seconds)996machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1997machine # [ 21.393715] nixos[1137]: switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test998machine # [ 21.396575] systemd[1]: Stopped target Local File Systems.999machine # [ 21.398251] systemd[1]: Stopped target Remote File Systems.1000machine # [ 21.671312] systemd[1]: Reload requested from client PID 1137 ('.switch-to-conf') (unit backdoor.service)...1001machine # [ 21.673523] systemd[1]: Reloading...1002machine # [ 21.988890] systemd[1]: Reloading finished in 313 ms.1003machine # [ 22.077858] systemd[563]: Reexecution requested from client PID 1206 ('switch-to-confi')...1004machine # [ 22.082885] systemd[563]: Reexecuting.1005machine # [ 22.140835] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1006machine # [ 22.255834] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1007machine # [ 22.263254] systemd[563]: Reload requested from client PID 1206 ('switch-to-confi')...1008machine # [ 22.265878] systemd[563]: Reloading...1009machine # [ 22.374769] systemd[563]: Reloading finished in 107 ms.1010machine # [ 22.388312] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1011machine # [ 22.399641] systemd[563]: Stopped Run user-specific NixOS activation.1012machine # [ 22.402565] systemd[563]: Stopping Run user-specific NixOS activation...1013machine # [ 22.404912] systemd[563]: Starting Run user-specific NixOS activation...1014machine # [ 22.443633] systemd[563]: Finished Run user-specific NixOS activation.1015machine # [ 22.448513] systemd[1]: Stopped target Reactivate sysinit units.1016machine # [ 22.450047] systemd[1]: Stopping Reactivate sysinit units...1017machine # [ 22.451007] systemd[1]: Reached target Reactivate sysinit units.1018machine # [ 22.455859] systemd[1]: Reloading D-Bus System Message Bus...1019machine # [ 22.457567] dbus-broker-launch[434]: Caught SIGHUP, trigger reload.1020machine # [ 22.463331] dbus-broker-launch[434]: Looking up NSS user entry for 'systemd-timesync'...1021machine # [ 22.466011] dbus-broker-launch[434]: NSS returned no entry for 'systemd-timesync'1022machine # [ 22.467183] dbus-broker-launch[434]: Invalid user-name in /nix/store/svs250hlinpmmh842v5vq65hzwi7rbkc-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1023machine # [ 22.480641] systemd[1]: Reloaded D-Bus System Message Bus.1024machine # [ 22.487875] systemd[1]: Stopping Address configuration of eth1...1025machine # [ 22.521449] network-addresses-eth1-pre-stop[1218]: deleting address 192.168.1.2/24... done1026machine # [ 22.530391] network-addresses-eth1-pre-stop[1218]: deleting address 2001:db8:1::2/64... done1027machine # [ 22.537961] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1028machine # [ 22.543211] systemd[1]: Stopped Address configuration of eth1.1029machine # [ 22.545985] systemd[1]: Starting Address configuration of eth1...1030machine # [ 22.592596] network-addresses-eth1-start[1223]: adding address 192.168.1.1/24... done1031machine # [ 22.600315] network-addresses-eth1-start[1223]: adding address 2001:db8:1::1/64... done1032machine # [ 22.610200] systemd[1]: Finished Address configuration of eth1.1033machine # [ 22.614449] systemd[1]: Reached target Local File Systems.1034machine # [ 22.622104] systemd[1]: Reached target Remote File Systems.1035machine # [ 22.627270] systemd[1]: Starting linger-users.service...1036machine # [ 22.634044] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1037machine # [ 22.641938] systemd[1]: Starting Create SUID/SGID Wrappers...1038machine # [ 22.643107] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1039machine # [ 22.653842] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671040machine # [ 22.663313] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1041machine # [ 22.668526] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1042machine # [ 22.670073] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1043machine # [ 22.671846] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1044machine # [ 22.682492] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1045machine # [ 22.709144] systemd[1]: linger-users.service: Deactivated successfully.1046machine # [ 22.713753] systemd[1]: Finished linger-users.service.1047machine # [ 22.862409] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1048machine # [ 22.865787] systemd[1]: Finished Create SUID/SGID Wrappers.1049machine # [ 23.120263] nixos[1137]: finished switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test1050machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.95 seconds)1051subtest: user services1052machine: waiting for unit user@1001.service1053machine: (finished: waiting for unit user@1001.service, in 0.07 seconds)1054machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11055machine # [ 23.384064] nixos[1296]: switching to system configuration /nix/store/a0krvhnw4d02dbzv739vbhn7wi243n8w-nixos-system-machine-test1056machine # [ 23.386903] systemd[1]: Stopped target Remote File Systems.1057machine # [ 23.389674] systemd[1]: Stopped target Local File Systems.1058machine # [ 23.718837] systemd[1]: Reload requested from client PID 1296 ('.switch-to-conf') (unit backdoor.service)...1059machine # [ 23.721020] systemd[1]: Reloading...1060machine # [ 24.241773] systemd[1]: Reloading finished in 518 ms.1061machine # [ 24.332233] systemd[563]: Reexecution requested from client PID 1366 ('switch-to-confi')...1062machine # [ 24.333733] systemd[563]: Reexecuting.1063machine # [ 24.387473] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1064machine # [ 24.506664] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1065machine # [ 24.513482] systemd[563]: Reload requested from client PID 1366 ('switch-to-confi')...1066machine # [ 24.517294] systemd[563]: Reloading...1067machine # [ 24.624340] systemd[563]: Reloading finished in 107 ms.1068machine # [ 24.641760] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1069machine # [ 24.652313] systemd[563]: Starting usertest.service...1070machine # [ 24.683456] systemd[563]: Finished usertest.service.1071machine # [ 24.687910] systemd[563]: Stopped Run user-specific NixOS activation.1072machine # [ 24.689236] systemd[563]: Stopping Run user-specific NixOS activation...1073machine # [ 24.691630] systemd[563]: Starting Run user-specific NixOS activation...1074machine # [ 24.726453] systemd[563]: Finished Run user-specific NixOS activation.1075machine # [ 24.732859] systemd[1]: Stopped target Reactivate sysinit units.1076machine # [ 24.733948] systemd[1]: Stopping Reactivate sysinit units...1077machine # [ 24.734876] systemd[1]: Reached target Reactivate sysinit units.1078machine # [ 24.746351] systemd[1]: Reached target Remote File Systems.1079machine # [ 24.749092] systemd[1]: Starting linger-users.service...1080machine # [ 24.756647] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1081machine # [ 24.758182] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1082machine # [ 24.759706] systemd[1]: Reached target Local File Systems.1083machine # [ 24.773016] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1084machine # [ 24.774906] systemd[1]: Starting Create SUID/SGID Wrappers...1085machine # [ 24.775779] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1086machine # [ 24.783540] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671087machine # [ 24.788543] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1088machine # [ 24.790454] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1089machine # [ 24.800622] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1090machine # [ 24.855810] systemd[1]: linger-users.service: Deactivated successfully.1091machine # [ 24.860129] systemd[1]: Finished linger-users.service.1092machine # [ 25.003840] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1093machine # [ 25.007063] systemd[1]: Finished Create SUID/SGID Wrappers.1094machine # [ 25.263107] nixos[1296]: finished switching to system configuration /nix/store/a0krvhnw4d02dbzv739vbhn7wi243n8w-nixos-system-machine-test1095machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.07 seconds)1096machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1097machine # [ 25.315554] sudo[1436]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1098machine # [ 25.331466] sudo[1436]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1099machine # [ 25.345872] sudo[1436]: pam_unix(sudo:session): session closed for user usertest1100machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.08 seconds)1101machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11102machine # [ 25.536771] nixos[1445]: switching to system configuration /nix/store/a0krvhnw4d02dbzv739vbhn7wi243n8w-nixos-system-machine-test1103machine # [ 25.539310] systemd[1]: Stopped target Remote File Systems.1104machine # [ 25.541528] systemd[1]: Stopped target Local File Systems.1105machine # [ 25.815324] systemd[1]: Reload requested from client PID 1445 ('.switch-to-conf') (unit backdoor.service)...1106machine # [ 25.817515] systemd[1]: Reloading...1107machine # [ 26.135963] systemd[1]: Reloading finished in 316 ms.1108machine # [ 26.223575] systemd[563]: Reexecution requested from client PID 1514 ('switch-to-confi')...1109machine # [ 26.226233] systemd[563]: Reexecuting.1110machine # [ 26.283300] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1111machine # [ 26.399594] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1112machine # [ 26.406185] systemd[563]: Reload requested from client PID 1514 ('switch-to-confi')...1113machine # [ 26.410009] systemd[563]: Reloading...1114machine # [ 26.518031] systemd[563]: Reloading finished in 108 ms.1115machine # [ 26.532178] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1116machine # [ 26.541875] systemd[563]: Stopped Run user-specific NixOS activation.1117machine # [ 26.544375] systemd[563]: Stopping Run user-specific NixOS activation...1118machine # [ 26.546515] systemd[563]: Starting Run user-specific NixOS activation...1119machine # [ 26.579790] systemd[563]: Finished Run user-specific NixOS activation.1120machine # [ 26.583819] systemd[1]: Stopped target Reactivate sysinit units.1121machine # [ 26.586825] systemd[1]: Stopping Reactivate sysinit units...1122machine # [ 26.587704] systemd[1]: Reached target Reactivate sysinit units.1123machine # [ 26.589664] systemd[1]: Reached target Local File Systems.1124machine # [ 26.593952] systemd[1]: Reached target Remote File Systems.1125machine # [ 26.598375] systemd[1]: Starting linger-users.service...1126machine # [ 26.608525] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1127machine # [ 26.610007] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1128machine # [ 26.614623] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1129machine # [ 26.624346] systemd[1]: Starting Create SUID/SGID Wrappers...1130machine # [ 26.625309] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1131machine # [ 26.626897] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671132machine # [ 26.639821] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1133machine # [ 26.644576] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1134machine # [ 26.645990] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1135machine # [ 26.684930] systemd[1]: linger-users.service: Deactivated successfully.1136machine # [ 26.688718] systemd[1]: Finished linger-users.service.1137machine # [ 26.825010] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1138machine # [ 26.828675] systemd[1]: Finished Create SUID/SGID Wrappers.1139machine # [ 27.082837] nixos[1445]: finished switching to system configuration /nix/store/a0krvhnw4d02dbzv739vbhn7wi243n8w-nixos-system-machine-test1140machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.74 seconds)1141machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&11142machine # [ 27.276096] nixos[1586]: switching to system configuration /nix/store/00l6rg5g44qsr24kglq18z3yvxn3iyf0-nixos-system-machine-test1143machine # [ 27.278677] systemd[1]: Stopped target Remote File Systems.1144machine # [ 27.281271] systemd[1]: Stopped target Local File Systems.1145machine # [ 27.562092] systemd[1]: Reload requested from client PID 1586 ('.switch-to-conf') (unit backdoor.service)...1146machine # [ 27.564375] systemd[1]: Reloading...1147machine # [ 27.879474] systemd[1]: Reloading finished in 313 ms.1148machine # [ 27.978000] systemd[563]: Stopped usertest.service.1149machine # [ 27.982031] systemd[563]: Reexecution requested from client PID 1655 ('switch-to-confi')...1150machine # [ 27.983557] systemd[563]: Reexecuting.1151machine # [ 28.039511] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1152machine # [ 28.153713] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1153machine # [ 28.161416] systemd[563]: Reload requested from client PID 1655 ('switch-to-confi')...1154machine # [ 28.162868] systemd[563]: Reloading...1155machine # [ 28.271006] systemd[563]: Reloading finished in 106 ms.1156machine # [ 28.289757] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1157machine # [ 28.298880] systemd[563]: Starting usertest.service...1158machine # [ 28.325359] systemd[563]: Finished usertest.service.1159machine # [ 28.329476] systemd[563]: Stopped Run user-specific NixOS activation.1160machine # [ 28.330678] systemd[563]: Stopping Run user-specific NixOS activation...1161machine # [ 28.332782] systemd[563]: Starting Run user-specific NixOS activation...1162machine # [ 28.363952] systemd[563]: Finished Run user-specific NixOS activation.1163machine # [ 28.368163] systemd[1]: Stopped target Reactivate sysinit units.1164machine # [ 28.370602] systemd[1]: Stopping Reactivate sysinit units...1165machine # [ 28.371548] systemd[1]: Reached target Reactivate sysinit units.1166machine # [ 28.373517] systemd[1]: Reached target Remote File Systems.1167machine # [ 28.378017] systemd[1]: Reached target Local File Systems.1168machine # [ 28.382529] systemd[1]: Starting linger-users.service...1169machine # [ 28.392763] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1170machine # [ 28.401663] systemd[1]: Starting Create SUID/SGID Wrappers...1171machine # [ 28.402652] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1172machine # [ 28.403971] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671173machine # [ 28.415158] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1174machine # [ 28.417054] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1175machine # [ 28.418420] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1176machine # [ 28.419669] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1177machine # [ 28.427036] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1178machine # [ 28.475494] systemd[1]: linger-users.service: Deactivated successfully.1179machine # [ 28.480169] systemd[1]: Finished linger-users.service.1180machine # [ 28.610614] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1181machine # [ 28.613640] systemd[1]: Finished Create SUID/SGID Wrappers.1182machine # [ 28.869480] nixos[1586]: finished switching to system configuration /nix/store/00l6rg5g44qsr24kglq18z3yvxn3iyf0-nixos-system-machine-test1183machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 1.79 seconds)1184machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1185machine # [ 28.912826] sudo[1725]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1186machine # [ 28.927534] sudo[1725]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1187machine # [ 28.941459] sudo[1725]: pam_unix(sudo:session): session closed for user usertest1188machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1189machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&11190machine # [ 29.125610] nixos[1734]: switching to system configuration /nix/store/fhjk6yz8lwlgf0dzagiwcckgvg3q6mpv-nixos-system-machine-test1191machine # [ 29.128853] systemd[1]: Stopped target Remote File Systems.1192machine # [ 29.130856] systemd[1]: Stopped target Local File Systems.1193machine # [ 29.409394] systemd[1]: Reload requested from client PID 1734 ('.switch-to-conf') (unit backdoor.service)...1194machine # [ 29.411398] systemd[1]: Reloading...1195machine # [ 29.719442] systemd[1]: Reloading finished in 306 ms.1196machine # [ 29.812428] systemd[563]: Reexecution requested from client PID 1803 ('switch-to-confi')...1197machine # [ 29.813881] systemd[563]: Reexecuting.1198machine # [ 29.871248] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1199machine # [ 29.988386] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1200machine # [ 29.994923] systemd[563]: Reload requested from client PID 1803 ('switch-to-confi')...1201machine # [ 29.998658] systemd[563]: Reloading...1202machine # [ 30.104396] systemd[563]: Reloading finished in 106 ms.1203machine # [ 30.120186] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1204machine # [ 30.126549] systemd[563]: Stopped usertest.service.1205machine # [ 30.128616] systemd[563]: Stopping usertest.service...1206machine # [ 30.130864] systemd[563]: Starting usertest.service...1207machine # [ 30.157275] systemd[563]: Finished usertest.service.1208machine # [ 30.162375] systemd[563]: Stopped Run user-specific NixOS activation.1209machine # [ 30.164777] systemd[563]: Stopping Run user-specific NixOS activation...1210machine # [ 30.166994] systemd[563]: Starting Run user-specific NixOS activation...1211machine # [ 30.214958] systemd[563]: Finished Run user-specific NixOS activation.1212machine # [ 30.223486] systemd[1]: Stopped target Reactivate sysinit units.1213machine # [ 30.230225] systemd[1]: Stopping Reactivate sysinit units...1214machine # [ 30.231302] systemd[1]: Reached target Reactivate sysinit units.1215machine # [ 30.234106] systemd[1]: Reached target Local File Systems.1216machine # [ 30.238093] systemd[1]: Reached target Remote File Systems.1217machine # [ 30.240875] systemd[1]: Starting linger-users.service...1218machine # [ 30.253956] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1219machine # [ 30.255540] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1220machine # [ 30.267059] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1221machine # [ 30.276546] systemd[1]: Starting Create SUID/SGID Wrappers...1222machine # [ 30.277486] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1223machine # [ 30.278818] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671224machine # [ 30.289927] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1225machine # [ 30.291735] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1226machine # [ 30.302883] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1227machine # [ 30.361670] systemd[1]: linger-users.service: Deactivated successfully.1228machine # [ 30.364179] systemd[1]: Finished linger-users.service.1229machine # [ 30.490165] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1230machine # [ 30.493872] systemd[1]: Finished Create SUID/SGID Wrappers.1231machine # [ 30.749876] nixos[1734]: finished switching to system configuration /nix/store/fhjk6yz8lwlgf0dzagiwcckgvg3q6mpv-nixos-system-machine-test1232machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1, in 1.81 seconds)1233machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1234machine # [ 30.791591] sudo[1874]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1235machine # [ 30.806732] sudo[1874]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1236machine # [ 30.821770] sudo[1874]: pam_unix(sudo:session): session closed for user usertest1237machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1238machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&11239machine # [ 31.008066] nixos[1883]: switching to system configuration /nix/store/avwfqxk3gm5hs6vhc6ydmvsk55sy1zfh-nixos-system-machine-test1240machine # [ 31.011822] systemd[1]: Stopped target Local File Systems.1241machine # [ 31.013578] systemd[1]: Stopped target Remote File Systems.1242machine # [ 31.295559] systemd[1]: Reload requested from client PID 1883 ('.switch-to-conf') (unit backdoor.service)...1243machine # [ 31.298821] systemd[1]: Reloading...1244machine # [ 31.636851] systemd[1]: Reloading finished in 336 ms.1245machine # [ 31.732453] systemd[563]: Reexecution requested from client PID 1952 ('switch-to-confi')...1246machine # [ 31.736125] systemd[563]: Reexecuting.1247machine # [ 31.791489] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1248machine # [ 31.907654] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1249machine # [ 31.914621] systemd[563]: Reload requested from client PID 1952 ('switch-to-confi')...1250machine # [ 31.917303] systemd[563]: Reloading...1251machine # [ 32.024970] systemd[563]: Reloading finished in 106 ms.1252machine # [ 32.040218] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1253machine # [ 32.049219] systemd[563]: Reloading usertest.service...1254machine # [ 32.073800] systemd[563]: Reloaded usertest.service.1255machine # [ 32.078958] systemd[563]: Stopped Run user-specific NixOS activation.1256machine # [ 32.081460] systemd[563]: Stopping Run user-specific NixOS activation...1257machine # [ 32.083590] systemd[563]: Starting Run user-specific NixOS activation...1258machine # [ 32.116974] systemd[563]: Finished Run user-specific NixOS activation.1259machine # [ 32.120690] systemd[1]: Stopped target Reactivate sysinit units.1260machine # [ 32.123762] systemd[1]: Stopping Reactivate sysinit units...1261machine # [ 32.126425] systemd[1]: Reached target Reactivate sysinit units.1262machine # [ 32.127369] systemd[1]: Reached target Local File Systems.1263machine # [ 32.131790] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1264machine # [ 32.140517] systemd[1]: Starting Create SUID/SGID Wrappers...1265machine # [ 32.141478] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1266machine # [ 32.142866] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671267machine # [ 32.155762] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1268machine # [ 32.157732] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1269machine # [ 32.159175] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1270machine # [ 32.161204] systemd[1]: Reached target Remote File Systems.1271machine # [ 32.162108] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1272machine # [ 32.163880] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1273machine # [ 32.327412] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1274machine # [ 32.332770] systemd[1]: Finished Create SUID/SGID Wrappers.1275machine # [ 32.334741] systemd[1]: Starting linger-users.service...1276machine # [ 32.379127] systemd[1]: linger-users.service: Deactivated successfully.1277machine # [ 32.381789] systemd[1]: Finished linger-users.service.1278machine # [ 32.637797] nixos[1883]: finished switching to system configuration /nix/store/avwfqxk3gm5hs6vhc6ydmvsk55sy1zfh-nixos-system-machine-test1279machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1, in 1.82 seconds)1280machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1281machine # [ 32.681563] sudo[2022]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1282machine # [ 32.696675] sudo[2022]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1283machine # [ 32.710564] sudo[2022]: pam_unix(sudo:session): session closed for user usertest1284machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1285machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11286machine # [ 32.900778] nixos[2031]: switching to system configuration /nix/store/a0krvhnw4d02dbzv739vbhn7wi243n8w-nixos-system-machine-test1287machine # [ 32.903481] systemd[1]: Stopped target Local File Systems.1288machine # [ 32.905645] systemd[1]: Stopped target Remote File Systems.1289machine # [ 33.170781] systemd[1]: Reload requested from client PID 2031 ('.switch-to-conf') (unit backdoor.service)...1290machine # [ 33.173099] systemd[1]: Reloading...1291machine # [ 33.486617] systemd[1]: Reloading finished in 312 ms.1292machine # [ 33.625955] systemd[563]: Stopped usertest.service.1293machine # [ 33.629758] systemd[563]: Reexecution requested from client PID 2100 ('switch-to-confi')...1294machine # [ 33.631288] systemd[563]: Reexecuting.1295machine # [ 33.691829] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1296machine # [ 33.811624] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1297machine # [ 33.819043] systemd[563]: Reload requested from client PID 2100 ('switch-to-confi')...1298machine # [ 33.821778] systemd[563]: Reloading...1299machine # [ 33.932184] systemd[563]: Reloading finished in 109 ms.1300machine # [ 33.948198] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1301machine # [ 33.959440] systemd[563]: Starting usertest.service...1302machine # [ 33.991019] systemd[563]: Finished usertest.service.1303machine # [ 33.994113] systemd[563]: Stopped Run user-specific NixOS activation.1304machine # [ 33.996720] systemd[563]: Stopping Run user-specific NixOS activation...1305machine # [ 33.998893] systemd[563]: Starting Run user-specific NixOS activation...1306machine # [ 34.034429] systemd[563]: Finished Run user-specific NixOS activation.1307machine # [ 34.038414] systemd[1]: Stopped target Reactivate sysinit units.1308machine # [ 34.040128] systemd[1]: Stopping Reactivate sysinit units...1309machine # [ 34.041026] systemd[1]: Reached target Reactivate sysinit units.1310machine # [ 34.047064] systemd[1]: Reached target Local File Systems.1311machine # [ 34.049911] systemd[1]: Reached target Remote File Systems.1312machine # [ 34.055227] systemd[1]: Starting linger-users.service...1313machine # [ 34.060266] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1314machine # [ 34.064401] systemd[1]: Starting Create SUID/SGID Wrappers...1315machine # [ 34.065332] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1316machine # [ 34.066678] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671317machine # [ 34.082436] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1318machine # [ 34.084261] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1319machine # [ 34.085597] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1320machine # [ 34.086873] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1321machine # [ 34.089413] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1322machine # [ 34.143132] systemd[1]: linger-users.service: Deactivated successfully.1323machine # [ 34.148580] systemd[1]: Finished linger-users.service.1324machine # [ 34.272826] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1325machine # [ 34.276729] systemd[1]: Finished Create SUID/SGID Wrappers.1326machine # [ 34.530605] nixos[2031]: finished switching to system configuration /nix/store/a0krvhnw4d02dbzv739vbhn7wi243n8w-nixos-system-machine-test1327machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.82 seconds)1328machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1329machine # [ 34.573082] sudo[2171]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1330machine # [ 34.587988] sudo[2171]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1331machine # [ 34.601887] sudo[2171]: pam_unix(sudo:session): session closed for user usertest1332machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1333machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&11334machine # [ 34.786825] nixos[2180]: switching to system configuration /nix/store/9l0csyzxsd2y9xkfd8i2d8kwf492fz20-nixos-system-machine-test1335machine # [ 34.789638] systemd[1]: Stopped target Local File Systems.1336machine # [ 34.792196] systemd[1]: Stopped target Remote File Systems.1337machine # [ 35.070161] systemd[1]: Reload requested from client PID 2180 ('.switch-to-conf') (unit backdoor.service)...1338machine # [ 35.072406] systemd[1]: Reloading...1339machine # [ 35.376278] systemd[1]: Reloading finished in 302 ms.1340machine # [ 35.464483] systemd[563]: Reexecution requested from client PID 2249 ('switch-to-confi')...1341machine # [ 35.468470] systemd[563]: Reexecuting.1342machine # [ 35.510912] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1343machine # [ 35.659362] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1344machine # [ 35.668081] systemd[563]: Reload requested from client PID 2249 ('switch-to-confi')...1345machine # [ 35.672052] systemd[563]: Reloading...1346machine # [ 35.777121] systemd[563]: Reloading finished in 105 ms.1347machine # [ 35.792180] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1348machine # [ 35.801380] systemd[563]: Reloading usertest.service...1349machine # [ 35.825118] systemd[563]: Reloaded usertest.service.1350machine # [ 35.830134] systemd[563]: Stopped Run user-specific NixOS activation.1351machine # [ 35.832466] systemd[563]: Stopping Run user-specific NixOS activation...1352machine # [ 35.834508] systemd[563]: Starting Run user-specific NixOS activation...1353machine # [ 35.866954] systemd[563]: Finished Run user-specific NixOS activation.1354machine # [ 35.871412] systemd[1]: Stopped target Reactivate sysinit units.1355machine # [ 35.876675] systemd[1]: Stopping Reactivate sysinit units...1356machine # [ 35.877626] systemd[1]: Reached target Reactivate sysinit units.1357machine # [ 35.878571] systemd[1]: Reached target Remote File Systems.1358machine # [ 35.880929] systemd[1]: Starting linger-users.service...1359machine # [ 35.892916] systemd[1]: Reached target Local File Systems.1360machine # [ 35.893831] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1361machine # [ 35.905435] systemd[1]: Starting Create SUID/SGID Wrappers...1362machine # [ 35.906442] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1363machine # [ 35.907753] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671364machine # [ 35.921024] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1365machine # [ 35.922860] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1366machine # [ 35.933194] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1367machine # [ 35.936420] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1368machine # [ 35.937854] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1369machine # [ 35.977046] systemd[1]: linger-users.service: Deactivated successfully.1370machine # [ 35.980387] systemd[1]: Finished linger-users.service.1371machine # [ 36.106934] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1372machine # [ 36.108843] systemd[1]: Finished Create SUID/SGID Wrappers.1373machine # [ 36.365302] nixos[2180]: finished switching to system configuration /nix/store/9l0csyzxsd2y9xkfd8i2d8kwf492fz20-nixos-system-machine-test1374machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 1.76 seconds)1375machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1376machine # [ 36.407391] sudo[2319]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1377machine # [ 36.421718] sudo[2319]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1378machine # [ 36.434789] sudo[2319]: pam_unix(sudo:session): session closed for user usertest1379machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1380machine: must fail: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&11381machine # [ 36.621877] nixos[2328]: switching to system configuration /nix/store/h1p77s6xwylm76s6vyq69hxqy2j1a4pl-nixos-system-machine-test1382machine # [ 36.625205] systemd[1]: Stopped target Local File Systems.1383machine # [ 36.627172] systemd[1]: Stopped target Remote File Systems.1384machine # [ 36.965742] systemd[1]: Reload requested from client PID 2328 ('.switch-to-conf') (unit backdoor.service)...1385machine # [ 36.967479] systemd[1]: Reloading...1386machine # [ 37.285631] systemd[1]: Reloading finished in 315 ms.1387machine # [ 37.373809] systemd[563]: Stopped usertest.service.1388machine # [ 37.377686] systemd[563]: Reexecution requested from client PID 2398 ('switch-to-confi')...1389machine # [ 37.379224] systemd[563]: Reexecuting.1390machine # [ 37.439453] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1391machine # [ 37.559677] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1392machine # [ 37.567461] systemd[563]: Reload requested from client PID 2398 ('switch-to-confi')...1393machine # [ 37.570892] systemd[563]: Reloading...1394machine # [ 37.678620] systemd[563]: Reloading finished in 109 ms.1395machine # [ 37.696181] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1396machine # [ 37.706891] systemd[563]: Starting usertest.service...1397machine # [ 37.733350] systemd[563]: usertest.service: Main process exited, code=exited, status=1/FAILURE1398machine # [ 37.736405] systemd[563]: usertest.service: Failed with result 'exit-code'.1399machine # [ 37.737843] systemd[563]: Failed to start usertest.service.1400machine # [ 37.743011] systemd[563]: Stopped Run user-specific NixOS activation.1401machine # [ 37.744630] systemd[563]: Stopping Run user-specific NixOS activation...1402machine # [ 37.745640] systemd[563]: Starting Run user-specific NixOS activation...1403machine # [ 37.779984] systemd[563]: Finished Run user-specific NixOS activation.1404machine # [ 37.784107] systemd[1]: Stopped target Reactivate sysinit units.1405machine # [ 37.786599] systemd[1]: Stopping Reactivate sysinit units...1406machine # [ 37.787534] systemd[1]: Reached target Reactivate sysinit units.1407machine # [ 37.792000] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1408machine # [ 37.793768] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1409machine # [ 37.797051] systemd[1]: Reached target Remote File Systems.1410machine # [ 37.799733] systemd[1]: Reached target Local File Systems.1411machine # [ 37.805793] systemd[1]: Starting linger-users.service...1412machine # [ 37.816594] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1413machine # [ 37.824529] systemd[1]: Starting Create SUID/SGID Wrappers...1414machine # [ 37.825592] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1415machine # [ 37.827054] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671416machine # [ 37.838537] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1417machine # [ 37.844456] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1418machine # [ 37.852473] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1419machine # [ 37.892780] systemd[1]: linger-users.service: Deactivated successfully.1420machine # [ 37.896239] systemd[1]: Finished linger-users.service.1421machine # [ 38.031521] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1422machine # [ 38.034602] systemd[1]: Finished Create SUID/SGID Wrappers.1423machine # [ 38.288664] nixos[2328]: switching to system configuration /nix/store/h1p77s6xwylm76s6vyq69hxqy2j1a4pl-nixos-system-machine-test failed (status 4)1424machine: (finished: must fail: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 1.86 seconds)1425machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11426machine # [ 38.497535] nixos[2471]: switching to system configuration /nix/store/a0krvhnw4d02dbzv739vbhn7wi243n8w-nixos-system-machine-test1427machine # [ 38.500059] systemd[1]: Stopped target Remote File Systems.1428machine # [ 38.502686] systemd[1]: Stopped target Local File Systems.1429machine # [ 38.771699] systemd[1]: Reload requested from client PID 2471 ('.switch-to-conf') (unit backdoor.service)...1430machine # [ 38.774022] systemd[1]: Reloading...1431machine # [ 39.088263] systemd[1]: Reloading finished in 312 ms.1432machine # [ 39.173309] systemd[563]: Reexecution requested from client PID 2540 ('switch-to-confi')...1433machine # [ 39.177718] systemd[563]: Reexecuting.1434machine # [ 39.231357] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1435machine # [ 39.342831] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1436machine # [ 39.349846] systemd[563]: Reload requested from client PID 2540 ('switch-to-confi')...1437machine # [ 39.354000] systemd[563]: Reloading...1438machine # [ 39.458354] systemd[563]: Reloading finished in 105 ms.1439machine # [ 39.473630] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1440machine # [ 39.482688] systemd[563]: Starting usertest.service...1441machine # [ 39.508970] systemd[563]: Finished usertest.service.1442machine # [ 39.512255] systemd[563]: Stopped Run user-specific NixOS activation.1443machine # [ 39.514497] systemd[563]: Stopping Run user-specific NixOS activation...1444machine # [ 39.516639] systemd[563]: Starting Run user-specific NixOS activation...1445machine # [ 39.547588] systemd[563]: Finished Run user-specific NixOS activation.1446machine # [ 39.551809] systemd[1]: Stopped target Reactivate sysinit units.1447machine # [ 39.555980] systemd[1]: Stopping Reactivate sysinit units...1448machine # [ 39.557875] systemd[1]: Reached target Reactivate sysinit units.1449machine # [ 39.558911] systemd[1]: Reached target Remote File Systems.1450machine # [ 39.564204] systemd[1]: Reached target Local File Systems.1451machine # [ 39.565162] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1452machine # [ 39.576856] systemd[1]: Starting Create SUID/SGID Wrappers...1453machine # [ 39.577937] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1454machine # [ 39.579305] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671455machine # [ 39.588191] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1456machine # [ 39.589916] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1457machine # [ 39.591261] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1458machine # [ 39.592621] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1459machine # [ 39.593969] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1460machine # [ 39.746534] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1461machine # [ 39.751664] systemd[1]: Finished Create SUID/SGID Wrappers.1462machine # [ 39.754243] systemd[1]: Starting linger-users.service...1463machine # [ 39.797374] systemd[1]: linger-users.service: Deactivated successfully.1464machine # [ 39.800652] systemd[1]: Finished linger-users.service.1465machine # [ 40.054769] nixos[2471]: finished switching to system configuration /nix/store/a0krvhnw4d02dbzv739vbhn7wi243n8w-nixos-system-machine-test1466machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.77 seconds)1467machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1468machine # [ 40.098005] sudo[2610]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1469machine # [ 40.113669] sudo[2610]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1470machine # [ 40.126550] sudo[2610]: pam_unix(sudo:session): session closed for user usertest1471machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1472machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&11473machine # [ 40.369748] nixos[2619]: switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test1474machine # [ 40.372844] systemd[1]: Stopped target Remote File Systems.1475machine # [ 40.375643] systemd[1]: Stopped target Local File Systems.1476machine # [ 40.643562] systemd[1]: Reload requested from client PID 2619 ('.switch-to-conf') (unit backdoor.service)...1477machine # [ 40.646048] systemd[1]: Reloading...1478machine # [ 40.955088] systemd[1]: Reloading finished in 307 ms.1479machine # [ 41.040697] systemd[563]: Stopped usertest.service.1480machine # [ 41.044409] systemd[563]: Reexecution requested from client PID 2689 ('switch-to-confi')...1481machine # [ 41.045831] systemd[563]: Reexecuting.1482machine # [ 41.103481] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1483machine # [ 41.218853] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1484machine # [ 41.225750] systemd[563]: Reload requested from client PID 2689 ('switch-to-confi')...1485machine # [ 41.228334] systemd[563]: Reloading...1486machine # [ 41.335179] systemd[563]: Reloading finished in 105 ms.1487machine # [ 41.352198] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1488machine # [ 41.362803] systemd[563]: Stopped Run user-specific NixOS activation.1489machine # [ 41.363882] systemd[563]: Stopping Run user-specific NixOS activation...1490machine # [ 41.368203] systemd[563]: Starting Run user-specific NixOS activation...1491machine # [ 41.395023] systemd[563]: Finished Run user-specific NixOS activation.1492machine # [ 41.399607] systemd[1]: Stopped target Reactivate sysinit units.1493machine # [ 41.402184] systemd[1]: Stopping Reactivate sysinit units...1494machine # [ 41.403213] systemd[1]: Reached target Reactivate sysinit units.1495machine # [ 41.408647] systemd[1]: Reached target Remote File Systems.1496machine # [ 41.411351] systemd[1]: Starting linger-users.service...1497machine # [ 41.414532] systemd[1]: Reached target Local File Systems.1498machine # [ 41.424798] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1499machine # [ 41.426271] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1500machine # [ 41.431562] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1501machine # [ 41.438813] systemd[1]: Starting Create SUID/SGID Wrappers...1502machine # [ 41.439762] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1503machine # [ 41.445920] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671504machine # [ 41.452325] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1505machine # [ 41.456900] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1506machine # [ 41.458357] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1507machine # [ 41.501138] systemd[1]: linger-users.service: Deactivated successfully.1508machine # [ 41.504145] systemd[1]: Finished linger-users.service.1509machine # [ 41.633437] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1510machine # [ 41.636917] systemd[1]: Finished Create SUID/SGID Wrappers.1511machine # [ 41.890965] nixos[2619]: finished switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test1512machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.77 seconds)1513machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1514machine # [ 41.933384] sudo[2757]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1515machine # [ 41.947879] sudo[2757]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1516machine # [ 41.963153] sudo[2757]: pam_unix(sudo:session): session closed for user usertest1517machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1518machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1519machine # [ 41.996826] sudo[2763]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1520machine # [ 42.007945] sudo[2763]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1521machine # [ 42.017086] sudo[2763]: pam_unix(sudo:session): session closed for user usertest1522machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.05 seconds)1523machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service1524machine # [ 42.044571] sudo[2769]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service /home/usertest/.config/systemd/user/migrated.service1525machine # [ 42.054333] sudo[2769]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1526machine # [ 42.062902] sudo[2769]: pam_unix(sudo:session): session closed for user usertest1527machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1528machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1529machine # [ 42.089427] sudo[2775]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service1530machine # [ 42.099073] sudo[2775]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1531machine # [ 42.107050] sudo[2775]: pam_unix(sudo:session): session closed for user usertest1532machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.04 seconds)1533machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1534machine # [ 42.132725] sudo[2781]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1535machine # [ 42.141990] sudo[2781]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1536machine # [ 42.150129] systemd[563]: Reload requested from client PID 2786 ('systemctl')...1537machine # [ 42.154054] systemd[563]: Reloading...1538machine # [ 42.261880] systemd[563]: Reloading finished in 109 ms.1539machine # [ 42.280325] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1540machine # [ 42.290670] sudo[2781]: pam_unix(sudo:session): session closed for user usertest1541machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.18 seconds)1542machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1543machine # [ 42.318485] sudo[2791]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1544machine # [ 42.328852] sudo[2791]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1545machine # [ 42.341865] systemd[563]: Starting migrated.service...1546machine # [ 42.366072] systemd[563]: Finished migrated.service.1547machine # [ 42.370303] sudo[2791]: pam_unix(sudo:session): session closed for user usertest1548machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.08 seconds)1549machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1550machine # [ 42.396975] sudo[2799]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1551machine # [ 42.406892] sudo[2799]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1552machine # [ 42.417076] sudo[2799]: pam_unix(sudo:session): session closed for user usertest1553machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)1554machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1555machine # [ 42.443142] sudo[2805]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1556machine # [ 42.452706] sudo[2805]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1557machine # [ 42.460318] sudo[2805]: pam_unix(sudo:session): session closed for user usertest1558machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.04 seconds)1559machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1560machine # [ 42.486541] sudo[2811]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service1561machine # [ 42.496921] sudo[2811]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1562machine # [ 42.512612] sudo[2811]: pam_unix(sudo:session): session closed for user usertest1563machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds)1564machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&11565machine # [ 42.687688] nixos[2820]: switching to system configuration /nix/store/fan85i4dd0va1lwihgr2s8ra0iqs0rwq-nixos-system-machine-test1566machine # [ 42.690890] systemd[1]: Stopped target Remote File Systems.1567machine # [ 42.692733] systemd[1]: Stopped target Local File Systems.1568machine # [ 42.961857] systemd[1]: Reload requested from client PID 2820 ('.switch-to-conf') (unit backdoor.service)...1569machine # [ 42.963911] systemd[1]: Reloading...1570machine # [ 43.267404] systemd[1]: Reloading finished in 301 ms.1571machine # [ 43.355509] systemd[563]: Reexecution requested from client PID 2889 ('switch-to-confi')...1572machine # [ 43.359375] systemd[563]: Reexecuting.1573machine # [ 43.407283] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1574machine # [ 43.569930] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1575machine # [ 43.580427] systemd[563]: Reload requested from client PID 2889 ('switch-to-confi')...1576machine # [ 43.581885] systemd[563]: Reloading...1577machine # [ 43.698696] systemd[563]: Reloading finished in 115 ms.1578machine # [ 43.716406] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1579machine # [ 43.727061] systemd[563]: Stopped Run user-specific NixOS activation.1580machine # [ 43.729667] systemd[563]: Stopping Run user-specific NixOS activation...1581machine # [ 43.732118] systemd[563]: Starting Run user-specific NixOS activation...1582machine # [ 43.788812] systemd[563]: Reload requested from client PID 2902 ('systemctl') (unit nixos-activation.service)...1583machine # [ 43.790659] systemd[563]: Reloading...1584machine # [ 43.906502] systemd[563]: Reloading finished in 114 ms.1585machine # [ 43.924196] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1586machine # [ 43.946303] systemd[563]: Stopped migrated.service.1587machine # [ 43.957774] systemd[563]: Finished Run user-specific NixOS activation.1588machine # [ 43.960889] systemd[563]: Reload requested from client PID 2889 ('switch-to-confi')...1589machine # [ 43.965100] systemd[563]: Reloading...1590machine # [ 44.074517] systemd[563]: Reloading finished in 110 ms.1591machine # [ 44.092403] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1592machine # [ 44.103670] systemd[563]: Starting migrated.service...1593machine # [ 44.135668] systemd[563]: Finished migrated.service.1594machine # [ 44.140641] systemd[1]: Stopped target Reactivate sysinit units.1595machine # [ 44.141676] systemd[1]: Stopping Reactivate sysinit units...1596machine # [ 44.142625] systemd[1]: Reached target Reactivate sysinit units.1597machine # [ 44.147900] systemd[1]: Reached target Local File Systems.1598machine # [ 44.152262] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1599machine # [ 44.159543] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1600machine # [ 44.163562] systemd[1]: Reached target Remote File Systems.1601machine # [ 44.168820] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1602machine # [ 44.170495] systemd[1]: Starting Create SUID/SGID Wrappers...1603machine # [ 44.171334] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1604machine # [ 44.178289] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671605machine # [ 44.180883] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1606machine # [ 44.182652] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1607machine # [ 44.183925] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1608machine # [ 44.343027] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1609machine # [ 44.348461] systemd[1]: Finished Create SUID/SGID Wrappers.1610machine # [ 44.350622] systemd[1]: Starting linger-users.service...1611machine # [ 44.393459] systemd[1]: linger-users.service: Deactivated successfully.1612machine # [ 44.396823] systemd[1]: Finished linger-users.service.1613machine # [ 44.651255] nixos[2820]: finished switching to system configuration /nix/store/fan85i4dd0va1lwihgr2s8ra0iqs0rwq-nixos-system-machine-test1614machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 2.14 seconds)1615machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1616machine # [ 44.699635] sudo[2972]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1617machine # [ 44.714934] sudo[2972]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1618machine # [ 44.729103] sudo[2972]: pam_unix(sudo:session): session closed for user usertest1619machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1620machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1621machine # [ 44.763256] sudo[2978]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service1622machine # [ 44.775162] sudo[2978]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1623machine # [ 44.794420] sudo[2978]: pam_unix(sudo:session): session closed for user usertest1624machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)1625machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1626machine # [ 44.824605] sudo[2984]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1627machine # [ 44.834347] sudo[2984]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1628machine # [ 44.843149] sudo[2984]: pam_unix(sudo:session): session closed for user usertest1629machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1630machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&11631machine # [ 45.019352] nixos[2993]: switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test1632machine # [ 45.023254] systemd[1]: Stopped target Local File Systems.1633machine # [ 45.024870] systemd[1]: Stopped target Remote File Systems.1634machine # [ 45.290788] systemd[1]: Reload requested from client PID 2993 ('.switch-to-conf') (unit backdoor.service)...1635machine # [ 45.292950] systemd[1]: Reloading...1636machine # [ 45.598679] systemd[1]: Reloading finished in 304 ms.1637machine # [ 45.684660] systemd[563]: Stopped migrated.service.1638machine # [ 45.688298] systemd[563]: Reexecution requested from client PID 3062 ('switch-to-confi')...1639machine # [ 45.689748] systemd[563]: Reexecuting.1640machine # [ 45.739175] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1641machine # [ 45.851739] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1642machine # [ 45.858758] systemd[563]: Reload requested from client PID 3062 ('switch-to-confi')...1643machine # [ 45.862704] systemd[563]: Reloading...1644machine # [ 45.970690] systemd[563]: Reloading finished in 109 ms.1645machine # [ 45.984183] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1646machine # [ 45.995108] systemd[563]: Stopped Run user-specific NixOS activation.1647machine # [ 45.997511] systemd[563]: Stopping Run user-specific NixOS activation...1648machine # [ 45.999694] systemd[563]: Starting Run user-specific NixOS activation...1649machine # [ 46.032906] systemd[563]: Finished Run user-specific NixOS activation.1650machine # [ 46.037022] systemd[1]: Stopped target Reactivate sysinit units.1651machine # [ 46.040152] systemd[1]: Stopping Reactivate sysinit units...1652machine # [ 46.041140] systemd[1]: Reached target Reactivate sysinit units.1653machine # [ 46.045962] systemd[1]: Reached target Local File Systems.1654machine # [ 46.048708] systemd[1]: Reached target Remote File Systems.1655machine # [ 46.053463] systemd[1]: Starting linger-users.service...1656machine # [ 46.062610] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1657machine # [ 46.071412] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1658machine # [ 46.076649] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1659machine # [ 46.078392] systemd[1]: Starting Create SUID/SGID Wrappers...1660machine # [ 46.079207] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1661machine # [ 46.087743] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671662machine # [ 46.095461] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1663machine # [ 46.101948] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1664machine # [ 46.103591] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1665machine # [ 46.143959] systemd[1]: linger-users.service: Deactivated successfully.1666machine # [ 46.148177] systemd[1]: Finished linger-users.service.1667machine # [ 46.277618] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1668machine # [ 46.280957] systemd[1]: Finished Create SUID/SGID Wrappers.1669machine # [ 46.534935] nixos[2993]: finished switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test1670machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.70 seconds)1671machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1672machine # [ 46.579200] sudo[3130]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1673machine # [ 46.594268] sudo[3130]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1674machine # [ 46.609650] sudo[3130]: pam_unix(sudo:session): session closed for user usertest1675machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1676machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1677machine # [ 46.642060] sudo[3136]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1678machine # [ 46.653853] sudo[3136]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1679machine # [ 46.662799] sudo[3136]: pam_unix(sudo:session): session closed for user usertest1680machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.05 seconds)1681machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service1682machine # [ 46.691082] sudo[3142]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service /home/usertest/.config/systemd/user/migrated.service1683machine # [ 46.701460] sudo[3142]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1684machine # [ 46.709543] sudo[3142]: pam_unix(sudo:session): session closed for user usertest1685machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1686machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1687machine # [ 46.736436] sudo[3148]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service1688machine # [ 46.746857] sudo[3148]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1689machine # [ 46.754251] sudo[3148]: pam_unix(sudo:session): session closed for user usertest1690machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.04 seconds)1691machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1692machine # [ 46.780743] sudo[3154]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1693machine # [ 46.790345] sudo[3154]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1694machine # [ 46.798518] systemd[563]: Reload requested from client PID 3159 ('systemctl')...1695machine # [ 46.802516] systemd[563]: Reloading...1696machine # [ 46.908592] systemd[563]: Reloading finished in 107 ms.1697machine # [ 46.920206] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1698machine # [ 46.933392] sudo[3154]: pam_unix(sudo:session): session closed for user usertest1699machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.18 seconds)1700machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1701machine # [ 46.962357] sudo[3164]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1702machine # [ 46.971796] sudo[3164]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1703machine # [ 46.985480] systemd[563]: Starting migrated.service...1704machine # [ 47.006573] systemd[563]: Finished migrated.service.1705machine # [ 47.010667] sudo[3164]: pam_unix(sudo:session): session closed for user usertest1706machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.08 seconds)1707machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1708machine # [ 47.038398] sudo[3172]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1709machine # [ 47.048818] sudo[3172]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1710machine # [ 47.058654] sudo[3172]: pam_unix(sudo:session): session closed for user usertest1711machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)1712machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1713machine # [ 47.085826] sudo[3178]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1714machine # [ 47.095485] sudo[3178]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1715machine # [ 47.103806] sudo[3178]: pam_unix(sudo:session): session closed for user usertest1716machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1717machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1718machine # [ 47.130409] sudo[3184]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service1719machine # [ 47.140788] sudo[3184]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1720machine # [ 47.156498] sudo[3184]: pam_unix(sudo:session): session closed for user usertest1721machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds)1722machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&11723machine # [ 47.331606] nixos[3193]: switching to system configuration /nix/store/mnzd39lra6879jpig7hjiqnxkl70bxcj-nixos-system-machine-test1724machine # [ 47.337214] systemd[1]: Stopped target Remote File Systems.1725machine # [ 47.338148] systemd[1]: Stopped target Local File Systems.1726machine # [ 47.607678] systemd[1]: Reload requested from client PID 3193 ('.switch-to-conf') (unit backdoor.service)...1727machine # [ 47.609914] systemd[1]: Reloading...1728machine # [ 47.912089] systemd[1]: Reloading finished in 300 ms.1729machine # [ 48.003552] systemd[563]: Reexecution requested from client PID 3262 ('switch-to-confi')...1730machine # [ 48.007389] systemd[563]: Reexecuting.1731machine # [ 48.055007] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1732machine # [ 48.165839] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1733machine # [ 48.172544] systemd[563]: Reload requested from client PID 3262 ('switch-to-confi')...1734machine # [ 48.176081] systemd[563]: Reloading...1735machine # [ 48.280992] systemd[563]: Reloading finished in 105 ms.1736machine # [ 48.292167] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1737machine # [ 48.301489] systemd[563]: Stopped Run user-specific NixOS activation.1738machine # [ 48.303916] systemd[563]: Stopping Run user-specific NixOS activation...1739machine # [ 48.306180] systemd[563]: Starting Run user-specific NixOS activation...1740machine # [ 48.344614] systemd[563]: Reload requested from client PID 3274 ('systemctl') (unit nixos-activation.service)...1741machine # [ 48.347667] systemd[563]: Reloading...1742machine # [ 48.458299] systemd[563]: Reloading finished in 109 ms.1743machine # [ 48.472172] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1744machine # [ 48.489315] systemd[563]: Finished Run user-specific NixOS activation.1745machine # [ 48.492147] systemd[563]: Reload requested from client PID 3262 ('switch-to-confi')...1746machine # [ 48.495994] systemd[563]: Reloading...1747machine # [ 48.602366] systemd[563]: Reloading finished in 107 ms.1748machine # [ 48.616174] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1749machine # [ 48.625554] systemd[563]: Stopped migrated.service.1750machine # [ 48.626429] systemd[563]: Stopping migrated.service...1751machine # [ 48.628262] systemd[563]: Starting migrated.service...1752machine # [ 48.654369] systemd[563]: Finished migrated.service.1753machine # [ 48.660823] systemd[1]: Stopped target Reactivate sysinit units.1754machine # [ 48.661805] systemd[1]: Stopping Reactivate sysinit units...1755machine # [ 48.662742] systemd[1]: Reached target Reactivate sysinit units.1756machine # [ 48.667244] systemd[1]: Reached target Remote File Systems.1757machine # [ 48.671736] systemd[1]: Starting linger-users.service...1758machine # [ 48.680405] systemd[1]: Reached target Local File Systems.1759machine # [ 48.682776] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1760machine # [ 48.687455] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1761machine # [ 48.695674] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1762machine # [ 48.704618] systemd[1]: Starting Create SUID/SGID Wrappers...1763machine # [ 48.705523] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1764machine # [ 48.706793] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671765machine # [ 48.715834] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1766machine # [ 48.717917] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1767machine # [ 48.719598] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1768machine # [ 48.755558] systemd[1]: linger-users.service: Deactivated successfully.1769machine # [ 48.758727] systemd[1]: Finished linger-users.service.1770machine # [ 48.890562] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1771machine # [ 48.893607] systemd[1]: Finished Create SUID/SGID Wrappers.1772machine # [ 49.149330] nixos[3193]: finished switching to system configuration /nix/store/mnzd39lra6879jpig7hjiqnxkl70bxcj-nixos-system-machine-test1773machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 2.00 seconds)1774machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1775machine # [ 49.192888] sudo[3343]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1776machine # [ 49.207547] sudo[3343]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1777machine # [ 49.222357] sudo[3343]: pam_unix(sudo:session): session closed for user usertest1778machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1779machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1780machine # [ 49.256280] sudo[3349]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service1781machine # [ 49.267357] sudo[3349]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1782machine # [ 49.285673] sudo[3349]: pam_unix(sudo:session): session closed for user usertest1783machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)1784machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1785machine # [ 49.312728] sudo[3355]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1786machine # [ 49.321873] sudo[3355]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1787machine # [ 49.328973] sudo[3355]: pam_unix(sudo:session): session closed for user usertest1788machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.04 seconds)1789machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&11790machine # [ 49.500189] nixos[3364]: switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test1791machine # [ 49.503971] systemd[1]: Stopped target Remote File Systems.1792machine # [ 49.506037] systemd[1]: Stopped target Local File Systems.1793machine # [ 49.759470] systemd[1]: Reload requested from client PID 3364 ('.switch-to-conf') (unit backdoor.service)...1794machine # [ 49.761590] systemd[1]: Reloading...1795machine # [ 50.057237] systemd[1]: Reloading finished in 294 ms.1796machine # [ 50.139756] systemd[563]: Stopped migrated.service.1797machine # [ 50.143143] systemd[563]: Reexecution requested from client PID 3433 ('switch-to-confi')...1798machine # [ 50.145919] systemd[563]: Reexecuting.1799machine # [ 50.208156] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1800machine # [ 50.322263] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1801machine # [ 50.329284] systemd[563]: Reload requested from client PID 3433 ('switch-to-confi')...1802machine # [ 50.330666] systemd[563]: Reloading...1803machine # [ 50.437912] systemd[563]: Reloading finished in 105 ms.1804machine # [ 50.452172] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1805machine # [ 50.461494] systemd[563]: Stopped Run user-specific NixOS activation.1806machine # [ 50.463853] systemd[563]: Stopping Run user-specific NixOS activation...1807machine # [ 50.466225] systemd[563]: Starting Run user-specific NixOS activation...1808machine # [ 50.499252] systemd[563]: Finished Run user-specific NixOS activation.1809machine # [ 50.503089] systemd[1]: Stopped target Reactivate sysinit units.1810machine # [ 50.505802] systemd[1]: Stopping Reactivate sysinit units...1811machine # [ 50.506800] systemd[1]: Reached target Reactivate sysinit units.1812machine # [ 50.509193] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1813machine # [ 50.510635] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1814machine # [ 50.514202] systemd[1]: Reached target Local File Systems.1815machine # [ 50.520726] systemd[1]: Reached target Remote File Systems.1816machine # [ 50.523883] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1817machine # [ 50.528142] systemd[1]: Starting Create SUID/SGID Wrappers...1818machine # [ 50.529039] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1819machine # [ 50.530336] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671820machine # [ 50.541332] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1821machine # [ 50.543173] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1822machine # [ 50.544690] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1823machine # [ 50.725389] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1824machine # [ 50.728648] systemd[1]: Finished Create SUID/SGID Wrappers.1825machine # [ 50.733682] systemd[1]: Starting linger-users.service...1826machine # [ 50.782780] systemd[1]: linger-users.service: Deactivated successfully.1827machine # [ 50.785976] systemd[1]: Finished linger-users.service.1828machine # [ 51.041398] nixos[3364]: finished switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test1829machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.72 seconds)1830machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1831machine # [ 51.085741] sudo[3501]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1832machine # [ 51.101459] sudo[3501]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1833machine # [ 51.117341] sudo[3501]: pam_unix(sudo:session): session closed for user usertest1834machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1835machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1836machine # [ 51.152719] sudo[3507]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1837machine # [ 51.163797] sudo[3507]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1838machine # [ 51.172653] sudo[3507]: pam_unix(sudo:session): session closed for user usertest1839machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.05 seconds)1840machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service1841machine # [ 51.202769] sudo[3513]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service /home/usertest/.config/systemd/user/migrated.service1842machine # [ 51.213290] sudo[3513]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1843machine # [ 51.221977] sudo[3513]: pam_unix(sudo:session): session closed for user usertest1844machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1845machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1846machine # [ 51.250706] sudo[3519]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service1847machine # [ 51.260877] sudo[3519]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1848machine # [ 51.268771] sudo[3519]: pam_unix(sudo:session): session closed for user usertest1849machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)1850machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1851machine # [ 51.294864] sudo[3525]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1852machine # [ 51.304995] sudo[3525]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1853machine # [ 51.312925] systemd[563]: Reload requested from client PID 3530 ('systemctl')...1854machine # [ 51.316796] systemd[563]: Reloading...1855machine # [ 51.425867] systemd[563]: Reloading finished in 110 ms.1856machine # [ 51.440174] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1857machine # [ 51.453510] sudo[3525]: pam_unix(sudo:session): session closed for user usertest1858machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.19 seconds)1859machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1860machine # [ 51.481515] sudo[3535]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1861machine # [ 51.491090] sudo[3535]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1862machine # [ 51.504305] systemd[563]: Starting migrated.service...1863machine # [ 51.530796] systemd[563]: Finished migrated.service.1864machine # [ 51.534936] sudo[3535]: pam_unix(sudo:session): session closed for user usertest1865machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.08 seconds)1866machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1867machine # [ 51.562616] sudo[3543]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1868machine # [ 51.572423] sudo[3543]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1869machine # [ 51.583281] sudo[3543]: pam_unix(sudo:session): session closed for user usertest1870machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)1871machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1872machine # [ 51.610300] sudo[3549]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1873machine # [ 51.619859] sudo[3549]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1874machine # [ 51.627727] sudo[3549]: pam_unix(sudo:session): session closed for user usertest1875machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.04 seconds)1876machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1877machine # [ 51.653826] sudo[3555]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service1878machine # [ 51.663558] sudo[3555]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1879machine # [ 51.679477] sudo[3555]: pam_unix(sudo:session): session closed for user usertest1880machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds)1881machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&11882machine # [ 51.858798] nixos[3564]: switching to system configuration /nix/store/j24581044ha8x58mcxfbg6flgpa65ivn-nixos-system-machine-test1883machine # [ 51.863009] systemd[1]: Stopped target Remote File Systems.1884machine # [ 51.863863] systemd[1]: Stopped target Local File Systems.1885machine # [ 52.162085] systemd[1]: Reload requested from client PID 3564 ('.switch-to-conf') (unit backdoor.service)...1886machine # [ 52.164189] systemd[1]: Reloading...1887machine # [ 52.466503] systemd[1]: Reloading finished in 300 ms.1888machine # [ 52.567501] systemd[563]: Reexecution requested from client PID 3633 ('switch-to-confi')...1889machine # [ 52.569763] systemd[563]: Reexecuting.1890machine # [ 52.631397] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1891machine # [ 52.748167] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1892machine # [ 52.754969] systemd[563]: Reload requested from client PID 3633 ('switch-to-confi')...1893machine # [ 52.757616] systemd[563]: Reloading...1894machine # [ 52.870170] systemd[563]: Reloading finished in 111 ms.1895machine # [ 52.884213] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1896machine # [ 52.893836] systemd[563]: Stopped Run user-specific NixOS activation.1897machine # [ 52.896090] systemd[563]: Stopping Run user-specific NixOS activation...1898machine # [ 52.898646] systemd[563]: Starting Run user-specific NixOS activation...1899machine # [ 52.937205] systemd[563]: Reload requested from client PID 3645 ('systemctl') (unit nixos-activation.service)...1900machine # [ 52.939976] systemd[563]: Reloading...1901machine # [ 53.054878] systemd[563]: Reloading finished in 114 ms.1902machine # [ 53.072193] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1903machine # [ 53.090011] systemd[563]: Finished Run user-specific NixOS activation.1904machine # [ 53.093418] systemd[563]: Reload requested from client PID 3633 ('switch-to-confi')...1905machine # [ 53.094764] systemd[563]: Reloading...1906machine # [ 53.206503] systemd[563]: Reloading finished in 110 ms.1907machine # [ 53.224188] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1908machine # [ 53.235186] systemd[563]: Reloading migrated.service...1909machine # [ 53.262176] systemd[563]: Reloaded migrated.service.1910machine # [ 53.269611] systemd[1]: Stopped target Reactivate sysinit units.1911machine # [ 53.273797] systemd[1]: Stopping Reactivate sysinit units...1912machine # [ 53.274901] systemd[1]: Reached target Reactivate sysinit units.1913machine # [ 53.278135] systemd[1]: Reached target Remote File Systems.1914machine # [ 53.283805] systemd[1]: Reached target Local File Systems.1915machine # [ 53.287768] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1916machine # [ 53.292250] systemd[1]: Starting Create SUID/SGID Wrappers...1917machine # [ 53.293162] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1918machine # [ 53.294433] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671919machine # [ 53.307303] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1920machine # [ 53.309352] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1921machine # [ 53.310762] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1922machine # [ 53.311989] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1923machine # [ 53.313377] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1924machine # [ 53.479521] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1925machine # [ 53.484868] systemd[1]: Finished Create SUID/SGID Wrappers.1926machine # [ 53.487233] systemd[1]: Starting linger-users.service...1927machine # [ 53.533370] systemd[1]: linger-users.service: Deactivated successfully.1928machine # [ 53.536494] systemd[1]: Finished linger-users.service.1929machine # [ 53.792542] nixos[3564]: finished switching to system configuration /nix/store/j24581044ha8x58mcxfbg6flgpa65ivn-nixos-system-machine-test1930machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 2.12 seconds)1931machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1932machine # [ 53.840672] sudo[3714]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1933machine # [ 53.857598] sudo[3714]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1934machine # [ 53.870962] sudo[3714]: pam_unix(sudo:session): session closed for user usertest1935machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1936machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1937machine # [ 53.905817] sudo[3720]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1938machine # [ 53.917435] sudo[3720]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1939machine # [ 53.927152] sudo[3720]: pam_unix(sudo:session): session closed for user usertest1940machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)1941machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&11942machine # [ 54.114774] nixos[3729]: switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test1943machine # [ 54.117447] systemd[1]: Stopped target Local File Systems.1944machine # [ 54.119957] systemd[1]: Stopped target Remote File Systems.1945machine # [ 54.400966] systemd[1]: Reload requested from client PID 3729 ('.switch-to-conf') (unit backdoor.service)...1946machine # [ 54.403111] systemd[1]: Reloading...1947machine # [ 54.733686] systemd[1]: Reloading finished in 329 ms.1948machine # [ 54.826965] systemd[563]: Stopped migrated.service.1949machine # [ 54.831090] systemd[563]: Reexecution requested from client PID 3798 ('switch-to-confi')...1950machine # [ 54.833211] systemd[563]: Reexecuting.1951machine # [ 54.887743] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1952machine # [ 55.005312] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1953machine # [ 55.012724] systemd[563]: Reload requested from client PID 3798 ('switch-to-confi')...1954machine # [ 55.017038] systemd[563]: Reloading...1955machine # [ 55.133431] systemd[563]: Reloading finished in 117 ms.1956machine # [ 55.148393] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1957machine # [ 55.158568] systemd[563]: Stopped Run user-specific NixOS activation.1958machine # [ 55.161526] systemd[563]: Stopping Run user-specific NixOS activation...1959machine # [ 55.164097] systemd[563]: Starting Run user-specific NixOS activation...1960machine # [ 55.200118] systemd[563]: Finished Run user-specific NixOS activation.1961machine # [ 55.204676] systemd[1]: Stopped target Reactivate sysinit units.1962machine # [ 55.208807] systemd[1]: Stopping Reactivate sysinit units...1963machine # [ 55.209862] systemd[1]: Reached target Reactivate sysinit units.1964machine # [ 55.213867] systemd[1]: Reached target Local File Systems.1965machine # [ 55.216925] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1966machine # [ 55.224732] systemd[1]: Starting Create SUID/SGID Wrappers...1967machine # [ 55.225772] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1968machine # [ 55.227118] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671969machine # [ 55.239442] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1970machine # [ 55.246647] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1971machine # [ 55.248976] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1972machine # [ 55.250326] systemd[1]: Reached target Remote File Systems.1973machine # [ 55.251156] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1974machine # [ 55.253027] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1975machine # [ 55.435145] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1976machine # [ 55.440860] systemd[1]: Finished Create SUID/SGID Wrappers.1977machine # [ 55.443896] systemd[1]: Starting linger-users.service...1978machine # [ 55.489574] systemd[1]: linger-users.service: Deactivated successfully.1979machine # [ 55.492985] systemd[1]: Finished linger-users.service.1980machine # [ 55.747212] nixos[3729]: finished switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test1981machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.82 seconds)1982machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1983machine # [ 55.792528] sudo[3866]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1984machine # [ 55.806986] sudo[3866]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1985machine # [ 55.821403] sudo[3866]: pam_unix(sudo:session): session closed for user usertest1986machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1987machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1988machine # [ 55.853278] sudo[3872]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1989machine # [ 55.863985] sudo[3872]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1990machine # [ 55.872816] sudo[3872]: pam_unix(sudo:session): session closed for user usertest1991machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.05 seconds)1992machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service1993machine # [ 55.902733] sudo[3878]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service /home/usertest/.config/systemd/user/migrated.service1994machine # [ 55.913130] sudo[3878]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1995machine # [ 55.921711] sudo[3878]: pam_unix(sudo:session): session closed for user usertest1996machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1997machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1998machine # [ 55.950328] sudo[3884]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service1999machine # [ 55.960791] sudo[3884]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2000machine # [ 55.969072] sudo[3884]: pam_unix(sudo:session): session closed for user usertest2001machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)2002machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2003machine # [ 55.997316] sudo[3890]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2004machine # [ 56.006996] sudo[3890]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2005machine # [ 56.016222] systemd[563]: Reload requested from client PID 3895 ('systemctl')...2006machine # [ 56.020223] systemd[563]: Reloading...2007machine # [ 56.134469] systemd[563]: Reloading finished in 115 ms.2008machine # [ 56.153839] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2009machine # [ 56.162645] sudo[3890]: pam_unix(sudo:session): session closed for user usertest2010machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.19 seconds)2011machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2012machine # [ 56.191073] sudo[3900]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2013machine # [ 56.201069] sudo[3900]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2014machine # [ 56.212448] systemd[563]: Starting migrated.service...2015machine # [ 56.242973] systemd[563]: Finished migrated.service.2016machine # [ 56.247717] sudo[3900]: pam_unix(sudo:session): session closed for user usertest2017machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.08 seconds)2018machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2019machine # [ 56.275619] sudo[3908]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2020machine # [ 56.285757] sudo[3908]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2021machine # [ 56.296235] sudo[3908]: pam_unix(sudo:session): session closed for user usertest2022machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)2023machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2024machine # [ 56.323409] sudo[3914]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2025machine # [ 56.333348] sudo[3914]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2026machine # [ 56.341326] sudo[3914]: pam_unix(sudo:session): session closed for user usertest2027machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.04 seconds)2028machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2029machine # [ 56.368540] sudo[3920]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2030machine # [ 56.378346] sudo[3920]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2031machine # [ 56.394821] sudo[3920]: pam_unix(sudo:session): session closed for user usertest2032machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds)2033machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&12034machine # [ 56.566778] nixos[3929]: switching to system configuration /nix/store/5q91xrzda1l6nzb4bgl9i97b0wqr1mhi-nixos-system-machine-test2035machine # [ 56.570938] systemd[1]: Stopped target Remote File Systems.2036machine # [ 56.571812] systemd[1]: Stopped target Local File Systems.2037machine # [ 56.845736] systemd[1]: Reload requested from client PID 3929 ('.switch-to-conf') (unit backdoor.service)...2038machine # [ 56.847822] systemd[1]: Reloading...2039machine # [ 57.159401] systemd[1]: Reloading finished in 310 ms.2040machine # [ 57.248107] systemd[563]: Reexecution requested from client PID 3998 ('switch-to-confi')...2041machine # [ 57.249570] systemd[563]: Reexecuting.2042machine # [ 57.307360] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2043machine # [ 57.423372] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2044machine # [ 57.430166] systemd[563]: Reload requested from client PID 3998 ('switch-to-confi')...2045machine # [ 57.433931] systemd[563]: Reloading...2046machine # [ 57.541116] systemd[563]: Reloading finished in 108 ms.2047machine # [ 57.556196] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2048machine # [ 57.566077] systemd[563]: Stopped Run user-specific NixOS activation.2049machine # [ 57.568072] systemd[563]: Stopping Run user-specific NixOS activation...2050machine # [ 57.570696] systemd[563]: Starting Run user-specific NixOS activation...2051machine # [ 57.607958] systemd[563]: Reload requested from client PID 4010 ('systemctl') (unit nixos-activation.service)...2052machine # [ 57.610858] systemd[563]: Reloading...2053machine # [ 57.727043] systemd[563]: Reloading finished in 115 ms.2054machine # [ 57.744188] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2055machine # [ 57.762147] systemd[563]: Finished Run user-specific NixOS activation.2056machine # [ 57.765023] systemd[563]: Reload requested from client PID 3998 ('switch-to-confi')...2057machine # [ 57.768858] systemd[563]: Reloading...2058machine # [ 57.877063] systemd[563]: Reloading finished in 109 ms.2059machine # [ 57.888215] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2060machine # [ 57.902529] systemd[1]: Stopped target Reactivate sysinit units.2061machine # [ 57.905913] systemd[1]: Stopping Reactivate sysinit units...2062machine # [ 57.906956] systemd[1]: Reached target Reactivate sysinit units.2063machine # [ 57.909278] systemd[1]: Reached target Remote File Systems.2064machine # [ 57.914393] systemd[1]: Reached target Local File Systems.2065machine # [ 57.917779] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2066machine # [ 57.922021] systemd[1]: Starting Create SUID/SGID Wrappers...2067machine # [ 57.922952] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2068machine # [ 57.930811] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672069machine # [ 57.937684] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2070machine # [ 57.939423] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2071machine # [ 57.942344] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2072machine # [ 57.943643] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2073machine # [ 57.945071] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2074machine # [ 58.117850] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2075machine # [ 58.123240] systemd[1]: Finished Create SUID/SGID Wrappers.2076machine # [ 58.126065] systemd[1]: Starting linger-users.service...2077machine # [ 58.174247] systemd[1]: linger-users.service: Deactivated successfully.2078machine # [ 58.177745] systemd[1]: Finished linger-users.service.2079machine # [ 58.433810] nixos[3929]: finished switching to system configuration /nix/store/5q91xrzda1l6nzb4bgl9i97b0wqr1mhi-nixos-system-machine-test2080machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 2.04 seconds)2081machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2082machine # [ 58.482257] sudo[4077]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2083machine # [ 58.497887] sudo[4077]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2084machine # [ 58.512603] sudo[4077]: pam_unix(sudo:session): session closed for user usertest2085machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)2086machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2087machine # [ 58.549915] sudo[4083]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2088machine # [ 58.561827] sudo[4083]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2089machine # [ 58.571864] sudo[4083]: pam_unix(sudo:session): session closed for user usertest2090machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)2091machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&12092machine # [ 58.757690] nixos[4092]: switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test2093machine # [ 58.760438] systemd[1]: Stopped target Remote File Systems.2094machine # [ 58.762942] systemd[1]: Stopped target Local File Systems.2095machine # [ 59.039192] systemd[1]: Reload requested from client PID 4092 ('.switch-to-conf') (unit backdoor.service)...2096machine # [ 59.041307] systemd[1]: Reloading...2097machine # [ 59.357357] systemd[1]: Reloading finished in 314 ms.2098machine # [ 59.446763] systemd[563]: Stopped migrated.service.2099machine # [ 59.451325] systemd[563]: Reexecution requested from client PID 4161 ('switch-to-confi')...2100machine # [ 59.453542] systemd[563]: Reexecuting.2101machine # [ 59.512338] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2102machine # [ 59.631205] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2103machine # [ 59.638516] systemd[563]: Reload requested from client PID 4161 ('switch-to-confi')...2104machine # [ 59.641450] systemd[563]: Reloading...2105machine # [ 59.755928] systemd[563]: Reloading finished in 113 ms.2106machine # [ 59.772261] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2107machine # [ 59.781925] systemd[563]: Stopped Run user-specific NixOS activation.2108machine # [ 59.784699] systemd[563]: Stopping Run user-specific NixOS activation...2109machine # [ 59.787072] systemd[563]: Starting Run user-specific NixOS activation...2110machine # [ 59.822854] systemd[563]: Finished Run user-specific NixOS activation.2111machine # [ 59.827509] systemd[1]: Stopped target Reactivate sysinit units.2112machine # [ 59.830439] systemd[1]: Stopping Reactivate sysinit units...2113machine # [ 59.831434] systemd[1]: Reached target Reactivate sysinit units.2114machine # [ 59.836810] systemd[1]: Reached target Local File Systems.2115machine # [ 59.837825] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2116machine # [ 59.849179] systemd[1]: Starting Create SUID/SGID Wrappers...2117machine # [ 59.850372] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2118machine # [ 59.851700] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672119machine # [ 59.866879] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2120machine # [ 59.868832] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2121machine # [ 59.870189] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2122machine # [ 59.871519] systemd[1]: Reached target Remote File Systems.2123machine # [ 59.872878] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2124machine # [ 59.874425] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2125machine # [ 60.053637] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2126machine # [ 60.056176] systemd[1]: Finished Create SUID/SGID Wrappers.2127machine # [ 60.061633] systemd[1]: Starting linger-users.service...2128machine # [ 60.110073] systemd[1]: linger-users.service: Deactivated successfully.2129machine # [ 60.113082] systemd[1]: Finished linger-users.service.2130machine # [ 60.370012] nixos[4092]: finished switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test2131machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.80 seconds)2132machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2133machine # [ 60.414561] sudo[4229]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2134machine # [ 60.429670] sudo[4229]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2135machine # [ 60.445408] sudo[4229]: pam_unix(sudo:session): session closed for user usertest2136machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)2137machine: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user2138machine # [ 60.480628] sudo[4235]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user2139machine # [ 60.491843] sudo[4235]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2140machine # [ 60.501722] sudo[4235]: pam_unix(sudo:session): session closed for user usertest2141machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user, in 0.06 seconds)2142machine: must succeed: sudo -u usertest cp /nix/store/kbn5jd7gqb07qksa4hgqhsx409fjnskk-migrated.service ~usertest/.local/share/systemd/user/migrated.service2143machine # [ 60.533062] sudo[4241]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/kbn5jd7gqb07qksa4hgqhsx409fjnskk-migrated.service /home/usertest/.local/share/systemd/user/migrated.service2144machine # [ 60.544832] sudo[4241]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2145machine # [ 60.553896] sudo[4241]: pam_unix(sudo:session): session closed for user usertest2146machine: (finished: must succeed: sudo -u usertest cp /nix/store/kbn5jd7gqb07qksa4hgqhsx409fjnskk-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.05 seconds)2147machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2148machine # [ 60.581233] sudo[4247]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2149machine # [ 60.591071] sudo[4247]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2150machine # [ 60.599662] systemd[563]: Reload requested from client PID 4252 ('systemctl')...2151machine # [ 60.603866] systemd[563]: Reloading...2152machine # [ 60.712539] systemd[563]: Reloading finished in 110 ms.2153machine # [ 60.728188] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2154machine # [ 60.741556] sudo[4247]: pam_unix(sudo:session): session closed for user usertest2155machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.19 seconds)2156machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2157machine # [ 60.769868] sudo[4257]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2158machine # [ 60.779595] sudo[4257]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2159machine # [ 60.795052] systemd[563]: Starting migrated.service...2160machine # [ 60.823219] systemd[563]: Finished migrated.service.2161machine # [ 60.827807] sudo[4257]: pam_unix(sudo:session): session closed for user usertest2162machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds)2163machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2164machine # [ 60.855529] sudo[4265]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2165machine # [ 60.865439] sudo[4265]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2166machine # [ 60.875839] sudo[4265]: pam_unix(sudo:session): session closed for user usertest2167machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)2168machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2169machine # [ 60.903194] sudo[4271]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2170machine # [ 60.913082] sudo[4271]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2171machine # [ 60.921039] sudo[4271]: pam_unix(sudo:session): session closed for user usertest2172machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.04 seconds)2173machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2174machine # [ 60.947757] sudo[4277]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2175machine # [ 60.958060] sudo[4277]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2176machine # [ 60.978330] sudo[4277]: pam_unix(sudo:session): session closed for user usertest2177machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)2178machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12179machine # [ 61.156673] nixos[4286]: switching to system configuration /nix/store/zcnavbxqjwcm6qww1p9w6q3laflll89k-nixos-system-machine-test2180machine # [ 61.159673] systemd[1]: Stopped target Remote File Systems.2181machine # [ 61.162111] systemd[1]: Stopped target Local File Systems.2182machine # [ 61.443149] systemd[1]: Reload requested from client PID 4286 ('.switch-to-conf') (unit backdoor.service)...2183machine # [ 61.445511] systemd[1]: Reloading...2184machine # [ 61.757730] systemd[1]: Reloading finished in 311 ms.2185machine # [ 61.848633] systemd[563]: Reexecution requested from client PID 4355 ('switch-to-confi')...2186machine # [ 61.853126] systemd[563]: Reexecuting.2187machine # [ 61.907091] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2188machine # [ 62.022248] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2189machine # [ 62.029753] systemd[563]: Reload requested from client PID 4355 ('switch-to-confi')...2190machine # [ 62.031232] systemd[563]: Reloading...2191machine # [ 62.139531] systemd[563]: Reloading finished in 107 ms.2192machine # [ 62.156185] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2193machine # [ 62.167997] systemd[563]: Stopped Run user-specific NixOS activation.2194machine # [ 62.169101] systemd[563]: Stopping Run user-specific NixOS activation...2195machine # [ 62.172151] systemd[563]: Starting Run user-specific NixOS activation...2196machine # [ 62.200988] systemd[563]: Finished Run user-specific NixOS activation.2197machine # [ 62.203245] systemd[563]: Reload requested from client PID 4355 ('switch-to-confi')...2198machine # [ 62.207585] systemd[563]: Reloading...2199machine # [ 62.317319] systemd[563]: Reloading finished in 111 ms.2200machine # [ 62.332186] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2201machine # [ 62.340685] systemd[563]: Stopped migrated.service.2202machine # [ 62.343051] systemd[563]: Stopping migrated.service...2203machine # [ 62.345102] systemd[563]: Starting migrated.service...2204machine # [ 62.370774] systemd[563]: Finished migrated.service.2205machine # [ 62.377360] systemd[1]: Stopped target Reactivate sysinit units.2206machine # [ 62.380946] systemd[1]: Stopping Reactivate sysinit units...2207machine # [ 62.381866] systemd[1]: Reached target Reactivate sysinit units.2208machine # [ 62.382744] systemd[1]: Reached target Local File Systems.2209machine # [ 62.385540] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2210machine # [ 62.386960] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2211machine # [ 62.391420] systemd[1]: Reached target Remote File Systems.2212machine # [ 62.398438] systemd[1]: Starting linger-users.service...2213machine # [ 62.404067] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2214machine # [ 62.412774] systemd[1]: Starting Create SUID/SGID Wrappers...2215machine # [ 62.413753] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2216machine # [ 62.415171] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672217machine # [ 62.424732] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2218machine # [ 62.426451] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2219machine # [ 62.427764] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2220machine # [ 62.483885] systemd[1]: linger-users.service: Deactivated successfully.2221machine # [ 62.486822] systemd[1]: Finished linger-users.service.2222machine # [ 62.615368] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2223machine # [ 62.618964] systemd[1]: Finished Create SUID/SGID Wrappers.2224machine # [ 62.873979] nixos[4286]: finished switching to system configuration /nix/store/zcnavbxqjwcm6qww1p9w6q3laflll89k-nixos-system-machine-test2225machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.90 seconds)2226machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2227machine # [ 62.916662] sudo[4429]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2228machine # [ 62.931388] sudo[4429]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2229machine # [ 62.946140] sudo[4429]: pam_unix(sudo:session): session closed for user usertest2230machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)2231machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2232machine # [ 62.979078] sudo[4435]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2233machine # [ 62.990588] sudo[4435]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2234machine # [ 63.008772] sudo[4435]: pam_unix(sudo:session): session closed for user usertest2235machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)2236machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2237machine # [ 63.037430] sudo[4441]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2238machine # [ 63.047011] sudo[4441]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2239machine # [ 63.055120] sudo[4441]: pam_unix(sudo:session): session closed for user usertest2240machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2241machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12242machine # [ 63.233890] nixos[4450]: switching to system configuration /nix/store/zcnavbxqjwcm6qww1p9w6q3laflll89k-nixos-system-machine-test2243machine # [ 63.237980] systemd[1]: Stopped target Remote File Systems.2244machine # [ 63.238933] systemd[1]: Stopped target Local File Systems.2245machine # [ 63.510104] systemd[1]: Reload requested from client PID 4450 ('.switch-to-conf') (unit backdoor.service)...2246machine # [ 63.512227] systemd[1]: Reloading...2247machine # [ 63.825164] systemd[1]: Reloading finished in 311 ms.2248machine # [ 63.912460] systemd[563]: Reexecution requested from client PID 4519 ('switch-to-confi')...2249machine # [ 63.915888] systemd[563]: Reexecuting.2250machine # [ 63.971610] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2251machine # [ 64.090804] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2252machine # [ 64.098922] systemd[563]: Reload requested from client PID 4519 ('switch-to-confi')...2253machine # [ 64.102475] systemd[563]: Reloading...2254machine # [ 64.214732] systemd[563]: Reloading finished in 113 ms.2255machine # [ 64.232255] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2256machine # [ 64.243135] systemd[563]: Stopped Run user-specific NixOS activation.2257machine # [ 64.245814] systemd[563]: Stopping Run user-specific NixOS activation...2258machine # [ 64.248261] systemd[563]: Starting Run user-specific NixOS activation...2259machine # [ 64.283985] systemd[563]: Finished Run user-specific NixOS activation.2260machine # [ 64.288671] systemd[1]: Stopped target Reactivate sysinit units.2261machine # [ 64.292912] systemd[1]: Stopping Reactivate sysinit units...2262machine # [ 64.294028] systemd[1]: Reached target Reactivate sysinit units.2263machine # [ 64.295854] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2264machine # [ 64.298977] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2265machine # [ 64.304818] systemd[1]: Reached target Local File Systems.2266machine # [ 64.305740] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2267machine # [ 64.309763] systemd[1]: Starting Create SUID/SGID Wrappers...2268machine # [ 64.310789] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2269machine # [ 64.320458] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672270machine # [ 64.322889] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2271machine # [ 64.331923] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2272machine # [ 64.333569] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2273machine # [ 64.335006] systemd[1]: Reached target Remote File Systems.2274machine # [ 64.510792] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2275machine # [ 64.513559] systemd[1]: Finished Create SUID/SGID Wrappers.2276machine # [ 64.519017] systemd[1]: Starting linger-users.service...2277machine # [ 64.565747] systemd[1]: linger-users.service: Deactivated successfully.2278machine # [ 64.569263] systemd[1]: Finished linger-users.service.2279machine # [ 64.826237] nixos[4450]: finished switching to system configuration /nix/store/zcnavbxqjwcm6qww1p9w6q3laflll89k-nixos-system-machine-test2280machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.78 seconds)2281machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd2282machine # [ 64.872771] sudo[4587]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd2283machine # [ 64.887773] sudo[4587]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2284machine # [ 64.899972] sudo[4587]: pam_unix(sudo:session): session closed for user usertest2285machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.07 seconds)2286machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2287machine # [ 64.934217] sudo[4593]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2288machine # [ 64.944926] sudo[4593]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2289machine # [ 64.954064] systemd[563]: Reload requested from client PID 4598 ('systemctl')...2290machine # [ 64.958238] systemd[563]: Reloading...2291machine # [ 65.068933] systemd[563]: Reloading finished in 111 ms.2292machine # [ 65.084286] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2293machine # [ 65.095671] sudo[4593]: pam_unix(sudo:session): session closed for user usertest2294machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.20 seconds)2295machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&12296machine # [ 65.278766] nixos[4606]: switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test2297machine # [ 65.281332] systemd[1]: Stopped target Local File Systems.2298machine # [ 65.283358] systemd[1]: Stopped target Remote File Systems.2299machine # [ 65.570886] systemd[1]: Reload requested from client PID 4606 ('.switch-to-conf') (unit backdoor.service)...2300machine # [ 65.573100] systemd[1]: Reloading...2301machine # [ 65.891078] systemd[1]: Reloading finished in 316 ms.2302machine # [ 65.977387] systemd[563]: Stopped migrated.service.2303machine # [ 65.980581] systemd[563]: Reexecution requested from client PID 4675 ('switch-to-confi')...2304machine # [ 65.984088] systemd[563]: Reexecuting.2305machine # [ 66.034992] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2306machine # [ 66.145015] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2307machine # [ 66.152108] systemd[563]: Reload requested from client PID 4675 ('switch-to-confi')...2308machine # [ 66.153482] systemd[563]: Reloading...2309machine # [ 66.257845] systemd[563]: Reloading finished in 102 ms.2310machine # [ 66.273594] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2311machine # [ 66.282700] systemd[563]: Stopped Run user-specific NixOS activation.2312machine # [ 66.285091] systemd[563]: Stopping Run user-specific NixOS activation...2313machine # [ 66.287263] systemd[563]: Starting Run user-specific NixOS activation...2314machine # [ 66.321875] systemd[563]: Finished Run user-specific NixOS activation.2315machine # [ 66.326591] systemd[1]: Stopped target Reactivate sysinit units.2316machine # [ 66.328973] systemd[1]: Stopping Reactivate sysinit units...2317machine # [ 66.329964] systemd[1]: Reached target Reactivate sysinit units.2318machine # [ 66.334651] systemd[1]: Reached target Remote File Systems.2319machine # [ 66.338732] systemd[1]: Reached target Local File Systems.2320machine # [ 66.341773] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2321machine # [ 66.348471] systemd[1]: Starting Create SUID/SGID Wrappers...2322machine # [ 66.349531] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2323machine # [ 66.350845] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672324machine # [ 66.365684] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2325machine # [ 66.367494] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2326machine # [ 66.369078] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2327machine # [ 66.370516] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2328machine # [ 66.371838] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2329machine # [ 66.535989] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2330machine # [ 66.538093] systemd[1]: Finished Create SUID/SGID Wrappers.2331machine # [ 66.543572] systemd[1]: Starting linger-users.service...2332machine # [ 66.587314] systemd[1]: linger-users.service: Deactivated successfully.2333machine # [ 66.590433] systemd[1]: Finished linger-users.service.2334machine # [ 66.845182] nixos[4606]: finished switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test2335machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.75 seconds)2336machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants2337machine # [ 66.880824] sudo[4743]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants2338machine # [ 66.893480] sudo[4743]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2339machine # [ 66.902040] sudo[4743]: pam_unix(sudo:session): session closed for user usertest2340machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.05 seconds)2341machine: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service2342machine # [ 66.933046] sudo[4749]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service /home/usertest/.config/systemd/user/migrated.service2343machine # [ 66.943612] sudo[4749]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2344machine # [ 66.952652] sudo[4749]: pam_unix(sudo:session): session closed for user usertest2345machine: (finished: must succeed: sudo -u usertest cp /nix/store/df2sq2jvlsbd1sllgaa36bxj9via6n1z-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)2346machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2347machine # [ 66.981685] sudo[4755]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service2348machine # [ 66.993050] sudo[4755]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2349machine # [ 67.000765] sudo[4755]: pam_unix(sudo:session): session closed for user usertest2350machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)2351machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2352machine # [ 67.028309] sudo[4761]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2353machine # [ 67.037849] sudo[4761]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2354machine # [ 67.046958] systemd[563]: Reload requested from client PID 4766 ('systemctl')...2355machine # [ 67.051086] systemd[563]: Reloading...2356machine # [ 67.161515] systemd[563]: Reloading finished in 111 ms.2357machine # [ 67.172174] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2358machine # [ 67.184235] sudo[4761]: pam_unix(sudo:session): session closed for user usertest2359machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.18 seconds)2360machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2361machine # [ 67.212651] sudo[4771]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2362machine # [ 67.222424] sudo[4771]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2363machine # [ 67.235976] systemd[563]: Starting migrated.service...2364machine # [ 67.264225] systemd[563]: Finished migrated.service.2365machine # [ 67.269174] sudo[4771]: pam_unix(sudo:session): session closed for user usertest2366machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds)2367machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2368machine # [ 67.302852] sudo[4779]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2369machine # [ 67.313858] sudo[4779]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2370machine # [ 67.325825] sudo[4779]: pam_unix(sudo:session): session closed for user usertest2371machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)2372machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2373machine # [ 67.357343] sudo[4785]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2374machine # [ 67.367385] sudo[4785]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2375machine # [ 67.376252] sudo[4785]: pam_unix(sudo:session): session closed for user usertest2376machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2377machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2378machine # [ 67.406368] sudo[4791]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2379machine # [ 67.416904] sudo[4791]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2380machine # [ 67.434850] sudo[4791]: pam_unix(sudo:session): session closed for user usertest2381machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)2382machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12383machine # [ 67.616481] nixos[4800]: switching to system configuration /nix/store/zcnavbxqjwcm6qww1p9w6q3laflll89k-nixos-system-machine-test2384machine # [ 67.619423] systemd[1]: Stopped target Local File Systems.2385machine # [ 67.621874] systemd[1]: Stopped target Remote File Systems.2386machine # [ 67.890355] systemd[1]: Reload requested from client PID 4800 ('.switch-to-conf') (unit backdoor.service)...2387machine # [ 67.892624] systemd[1]: Reloading...2388machine # [ 68.203274] systemd[1]: Reloading finished in 309 ms.2389machine # [ 68.289674] systemd[563]: Reexecution requested from client PID 4869 ('switch-to-confi')...2390machine # [ 68.293385] systemd[563]: Reexecuting.2391machine # [ 68.347247] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2392machine # [ 68.462159] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2393machine # [ 68.469018] systemd[563]: Reload requested from client PID 4869 ('switch-to-confi')...2394machine # [ 68.471660] systemd[563]: Reloading...2395machine # [ 68.579065] systemd[563]: Reloading finished in 106 ms.2396machine # [ 68.593785] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2397machine # [ 68.601318] systemd[563]: Stopped Run user-specific NixOS activation.2398machine # [ 68.603775] systemd[563]: Stopping Run user-specific NixOS activation...2399machine # [ 68.606124] systemd[563]: Starting Run user-specific NixOS activation...2400machine # [ 68.635886] systemd[563]: Finished Run user-specific NixOS activation.2401machine # [ 68.638686] systemd[563]: Reload requested from client PID 4869 ('switch-to-confi')...2402machine # [ 68.642714] systemd[563]: Reloading...2403machine # [ 68.752121] systemd[563]: Reloading finished in 111 ms.2404machine # [ 68.769821] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2405machine # [ 68.781042] systemd[1]: Stopped target Reactivate sysinit units.2406machine # [ 68.783103] systemd[1]: Stopping Reactivate sysinit units...2407machine # [ 68.785795] systemd[1]: Reached target Reactivate sysinit units.2408machine # [ 68.788980] systemd[1]: Reached target Remote File Systems.2409machine # [ 68.791758] systemd[1]: Reached target Local File Systems.2410machine # [ 68.796225] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2411machine # [ 68.805196] systemd[1]: Starting Create SUID/SGID Wrappers...2412machine # [ 68.806339] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2413machine # [ 68.807614] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672414machine # [ 68.818289] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2415machine # [ 68.820091] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2416machine # [ 68.821412] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2417machine # [ 68.822663] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2418machine # [ 68.823974] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2419machine # [ 68.990714] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2420machine # [ 68.995872] systemd[1]: Finished Create SUID/SGID Wrappers.2421machine # [ 68.998379] systemd[1]: Starting linger-users.service...2422machine # [ 69.033258] systemd[1]: linger-users.service: Deactivated successfully.2423machine # [ 69.036649] systemd[1]: Finished linger-users.service.2424machine # [ 69.290895] nixos[4800]: finished switching to system configuration /nix/store/zcnavbxqjwcm6qww1p9w6q3laflll89k-nixos-system-machine-test2425machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.86 seconds)2426machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2427machine # [ 69.333435] sudo[4941]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2428machine # [ 69.348726] sudo[4941]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2429machine # [ 69.372119] sudo[4941]: pam_unix(sudo:session): session closed for user usertest2430machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.08 seconds)2431machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2432machine # [ 69.407409] sudo[4947]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2433machine # [ 69.419186] sudo[4947]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2434machine # [ 69.428127] sudo[4947]: pam_unix(sudo:session): session closed for user usertest2435machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2436machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd2437machine # [ 69.457260] sudo[4953]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd2438machine # [ 69.466660] sudo[4953]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2439machine # [ 69.474806] sudo[4953]: pam_unix(sudo:session): session closed for user usertest2440machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.05 seconds)2441machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2442machine # [ 69.501418] sudo[4959]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2443machine # [ 69.510899] sudo[4959]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2444machine # [ 69.519528] systemd[563]: Reload requested from client PID 4964 ('systemctl')...2445machine # [ 69.521928] systemd[563]: Reloading...2446machine # [ 69.632265] systemd[563]: Reloading finished in 108 ms.2447machine # [ 69.644167] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2448machine # [ 69.658051] sudo[4959]: pam_unix(sudo:session): session closed for user usertest2449machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.18 seconds)2450machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service2451machine # [ 69.686144] sudo[4969]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user stop migrated.service2452machine # [ 69.696767] sudo[4969]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2453machine # [ 69.707868] systemd[563]: Stopped migrated.service.2454machine # [ 69.712074] sudo[4969]: pam_unix(sudo:session): session closed for user usertest2455machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service, in 0.05 seconds)2456machine: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&12457machine # [ 69.884911] nixos[4978]: switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test2458machine # [ 69.890243] systemd[1]: Stopped target Remote File Systems.2459machine # [ 69.891205] systemd[1]: Stopped target Local File Systems.2460machine # [ 70.152639] systemd[1]: Reload requested from client PID 4978 ('.switch-to-conf') (unit backdoor.service)...2461machine # [ 70.155696] systemd[1]: Reloading...2462machine # [ 70.479384] systemd[1]: Reloading finished in 322 ms.2463machine # [ 70.570500] systemd[563]: Reexecution requested from client PID 5047 ('switch-to-confi')...2464machine # [ 70.571963] systemd[563]: Reexecuting.2465machine # [ 70.623162] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2466machine # [ 70.734865] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2467machine # [ 70.741948] systemd[563]: Reload requested from client PID 5047 ('switch-to-confi')...2468machine # [ 70.744541] systemd[563]: Reloading...2469machine # [ 70.853990] systemd[563]: Reloading finished in 108 ms.2470machine # [ 70.868185] systemd[563]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2471machine # [ 70.877132] systemd[563]: Stopped Run user-specific NixOS activation.2472machine # [ 70.879713] systemd[563]: Stopping Run user-specific NixOS activation...2473machine # [ 70.882247] systemd[563]: Starting Run user-specific NixOS activation...2474machine # [ 70.912823] systemd[563]: Finished Run user-specific NixOS activation.2475machine # [ 70.917023] systemd[1]: Stopped target Reactivate sysinit units.2476machine # [ 70.921212] systemd[1]: Stopping Reactivate sysinit units...2477machine # [ 70.922201] systemd[1]: Reached target Reactivate sysinit units.2478machine # [ 70.923743] systemd[1]: Reached target Remote File Systems.2479machine # [ 70.928249] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2480machine # [ 70.929692] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2481machine # [ 70.935824] systemd[1]: Starting linger-users.service...2482machine # [ 70.941149] systemd[1]: Reached target Local File Systems.2483machine # [ 70.942190] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2484machine # [ 70.956153] systemd[1]: Starting Create SUID/SGID Wrappers...2485machine # [ 70.957221] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2486machine # [ 70.958609] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672487machine # [ 70.965240] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2488machine # [ 70.967124] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2489machine # [ 70.968744] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2490machine # [ 71.026632] systemd[1]: linger-users.service: Deactivated successfully.2491machine # [ 71.031158] systemd[1]: Finished linger-users.service.2492machine # [ 71.158576] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2493machine # [ 71.161676] systemd[1]: Finished Create SUID/SGID Wrappers.2494machine # [ 71.417488] nixos[4978]: finished switching to system configuration /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test2495machine: (finished: must succeed: /nix/store/w70cl9lfqlq0l02ny66m9qrbmkgjlfn1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.71 seconds)2496(finished: subtest: user services, in 48.30 seconds)2497(finished: run the VM test script, in 72.17 seconds)2498test script finished in 72.25s2499cleanup2500kill QemuMachine (pid 45)2501machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)2502machine # [2026-09-27T03:14:26Z INFO virtiofsd] Client disconnected, shutting down2503machine # [2026-09-27T03:14:26Z INFO virtiofsd] Client disconnected, shutting down2504machine # [2026-09-27T03:14:26Z INFO virtiofsd] Client disconnected, shutting down2505(finished: cleanup, in 0.13 seconds)2506additionally exposed symbols:2507 machine, other,2508 vlan1,2509 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